Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/568/
1 tests failed. FAILED: org.apache.solr.cloud.AliasIntegrationTest.testModifyPropertiesV2 Error Message: Error from server at https://127.0.0.1:39751/solr: Collection : collection2meta is part of alias testModifyPropertiesV2 remove or modify the alias before removing this collection. Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:39751/solr: Collection : collection2meta is part of alias testModifyPropertiesV2 remove or modify the alias before removing this collection. at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:451) at org.apache.solr.cloud.AliasIntegrationTest.tearDown(AliasIntegrationTest.java:92) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13474 lines...] [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/init-core-data-001 [junit4] 2> 943529 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 943530 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 943530 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 943530 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001 [junit4] 2> 943530 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 943531 INFO (Thread-3409) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 943531 INFO (Thread-3409) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 943532 ERROR (Thread-3409) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 943631 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:59072 [junit4] 2> 943635 INFO (zkConnectionManagerCallback-2551-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943643 INFO (jetty-launcher-2548-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 943646 INFO (jetty-launcher-2548-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 943646 INFO (jetty-launcher-2548-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 943646 INFO (jetty-launcher-2548-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 943647 INFO (jetty-launcher-2548-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 943647 INFO (jetty-launcher-2548-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@50524085{/solr,null,AVAILABLE} [junit4] 2> 943660 INFO (jetty-launcher-2548-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@46823bf9{SSL,[ssl, http/1.1]}{127.0.0.1:39751} [junit4] 2> 943663 INFO (jetty-launcher-2548-thread-1) [ ] o.e.j.s.Server Started @943701ms [junit4] 2> 943663 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39751} [junit4] 2> 943664 ERROR (jetty-launcher-2548-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 943664 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 943664 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0 [junit4] 2> 943664 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 943664 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 943664 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-18T03:00:25.125Z [junit4] 2> 943665 INFO (jetty-launcher-2548-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 943665 INFO (jetty-launcher-2548-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 943665 INFO (jetty-launcher-2548-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 943665 INFO (jetty-launcher-2548-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@509463f5{/solr,null,AVAILABLE} [junit4] 2> 943665 INFO (jetty-launcher-2548-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f972827{SSL,[ssl, http/1.1]}{127.0.0.1:49100} [junit4] 2> 943665 INFO (jetty-launcher-2548-thread-2) [ ] o.e.j.s.Server Started @943707ms [junit4] 2> 943666 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49100} [junit4] 2> 943666 ERROR (jetty-launcher-2548-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 943666 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 943666 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0 [junit4] 2> 943666 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 943666 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 943666 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-18T03:00:25.127Z [junit4] 2> 943667 INFO (zkConnectionManagerCallback-2555-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943667 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 943675 INFO (jetty-launcher-2548-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59072/solr [junit4] 2> 943699 INFO (zkConnectionManagerCallback-2553-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943700 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 943743 INFO (zkConnectionManagerCallback-2559-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943751 INFO (jetty-launcher-2548-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59072/solr [junit4] 2> 943753 INFO (zkConnectionManagerCallback-2563-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943754 INFO (zkConnectionManagerCallback-2565-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943759 INFO (zkConnectionManagerCallback-2567-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943854 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 943854 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 943855 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39751_solr [junit4] 2> 943856 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49100_solr [junit4] 2> 943856 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.Overseer Overseer (id=73648305009065990-127.0.0.1:39751_solr-n_0000000000) starting [junit4] 2> 943865 INFO (zkCallback-2564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 943885 INFO (zkCallback-2566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 943951 INFO (zkConnectionManagerCallback-2576-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 943952 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 943952 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59072/solr ready [junit4] 2> 943968 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 943968 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39751_solr [junit4] 2> 943972 INFO (zkCallback-2566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 944023 INFO (zkCallback-2575-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 944024 INFO (zkCallback-2564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 944083 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39751.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 944090 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39751.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 944090 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39751.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 944091 INFO (jetty-launcher-2548-thread-1) [n:127.0.0.1:39751_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/. [junit4] 2> 944608 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49100.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 944614 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49100.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 944614 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49100.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 944615 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node2/. [junit4] 2> 944618 INFO (zkConnectionManagerCallback-2581-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 944618 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 944619 INFO (jetty-launcher-2548-thread-2) [n:127.0.0.1:49100_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59072/solr ready [junit4] 2> 944650 INFO (zkConnectionManagerCallback-2583-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 944668 INFO (zkConnectionManagerCallback-2588-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 944668 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 944669 INFO (SUITE-AliasIntegrationTest-seed#[816EEC511566DBB2]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59072/solr ready [junit4] 2> 944689 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[816EEC511566DBB2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testModifyPropertiesV2 [junit4] 2> 944718 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 944728 INFO (OverseerThreadFactory-3259-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta [junit4] 2> 944839 INFO (OverseerStateUpdate-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1meta", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1meta_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:39751/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 944841 INFO (OverseerStateUpdate-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1meta", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection1meta_shard2_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:49100/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 945086 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 945148 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 945149 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 946103 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 946109 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal [junit4] 2> 946112 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 946112 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1' using configuration from collection collection1meta, trusted=true [junit4] 2> 946112 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39751.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 946113 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 946113 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/collection1meta_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/./collection1meta_shard1_replica_n1/data/] [junit4] 2> 946163 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 946169 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n3] Schema name=minimal [junit4] 2> 946172 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 946172 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n3' using configuration from collection collection1meta, trusted=true [junit4] 2> 946174 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49100.solr.core.collection1meta.shard2.replica_n3' (registry 'solr.core.collection1meta.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 946175 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 946175 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n3] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node2/collection1meta_shard2_replica_n3], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node2/./collection1meta_shard2_replica_n3/data/] [junit4] 2> 946188 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 946188 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 946190 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 946190 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 946191 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2bb170ef[collection1meta_shard1_replica_n1] main] [junit4] 2> 946192 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 946192 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 946193 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 946193 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1598051243947720704 [junit4] 2> 946195 INFO (searcherExecutor-3264-thread-1-processing-n:127.0.0.1:39751_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node2) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@2bb170ef[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 946199 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 946201 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 946201 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 946201 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39751/solr/collection1meta_shard1_replica_n1/ [junit4] 2> 946201 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 946201 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:39751/solr/collection1meta_shard1_replica_n1/ has no replicas [junit4] 2> 946201 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 946204 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:39751/solr/collection1meta_shard1_replica_n1/ shard1 [junit4] 2> 946231 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 946231 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 946232 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 946232 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 946237 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@17cc8b0d[collection1meta_shard2_replica_n3] main] [junit4] 2> 946238 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 946239 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 946246 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 946246 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1598051244003295232 [junit4] 2> 946249 INFO (searcherExecutor-3266-thread-1-processing-n:127.0.0.1:49100_solr x:collection1meta_shard2_replica_n3 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SolrCore [collection1meta_shard2_replica_n3] Registered new searcher Searcher@17cc8b0d[collection1meta_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 946251 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 946253 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 946253 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 946253 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:49100/solr/collection1meta_shard2_replica_n3/ [junit4] 2> 946253 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 946254 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SyncStrategy https://127.0.0.1:49100/solr/collection1meta_shard2_replica_n3/ has no replicas [junit4] 2> 946254 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 946256 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:49100/solr/collection1meta_shard2_replica_n3/ shard2 [junit4] 2> 946358 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 946359 INFO (qtp1796266967-10429) [n:127.0.0.1:49100_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_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=collection1meta_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1210 [junit4] 2> 946406 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 946409 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_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=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1322 [junit4] 2> 946413 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 946509 INFO (zkCallback-2564-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 946510 INFO (zkCallback-2566-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 946730 INFO (OverseerCollectionConfigSetProcessor-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 947413 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2695 [junit4] 2> 947414 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 947415 INFO (OverseerThreadFactory-3259-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2meta [junit4] 2> 947535 INFO (OverseerStateUpdate-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection2meta", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection2meta_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:39751/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 947639 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 948652 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 948659 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.IndexSchema [collection2meta_shard1_replica_n1] Schema name=minimal [junit4] 2> 948661 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 948662 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection2meta_shard1_replica_n1' using configuration from collection collection2meta, trusted=true [junit4] 2> 948662 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39751.solr.core.collection2meta.shard1.replica_n1' (registry 'solr.core.collection2meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 948662 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 948662 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore [[collection2meta_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/collection2meta_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/./collection2meta_shard1_replica_n1/data/] [junit4] 2> 948718 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 948718 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 948720 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 948720 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 948721 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e4e70b4[collection2meta_shard1_replica_n1] main] [junit4] 2> 948722 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 948723 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 948723 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 948723 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1598051246600617984 [junit4] 2> 948725 INFO (searcherExecutor-3274-thread-1-processing-n:127.0.0.1:39751_solr x:collection2meta_shard1_replica_n1 c:collection2meta s:shard1 r:core_node2) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] Registered new searcher Searcher@3e4e70b4[collection2meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 948729 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 948733 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 948733 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 948733 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39751/solr/collection2meta_shard1_replica_n1/ [junit4] 2> 948734 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 948734 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:39751/solr/collection2meta_shard1_replica_n1/ has no replicas [junit4] 2> 948734 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 948738 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:39751/solr/collection2meta_shard1_replica_n1/ shard1 [junit4] 2> 948840 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 948844 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_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=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1204 [junit4] 2> 948850 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 948942 INFO (zkCallback-2566-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 949416 INFO (OverseerCollectionConfigSetProcessor-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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> 949855 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2440 [junit4] 2> 949892 ERROR (OverseerThreadFactory-3259-thread-3) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: testModifyPropertiesV2 operation: aliasprop failed:org.apache.solr.common.SolrException: Can't modify non-existent alias testModifyPropertiesV2 [junit4] 2> at org.apache.solr.cloud.api.collections.SetAliasPropCmd.call(SetAliasPropCmd.java:57) [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:252) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192) [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> 949894 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/c params={} status=400 QTime=18 [junit4] 2> { [junit4] 2> "responseHeader":{ [junit4] 2> "status":400, [junit4] 2> "QTime":18}, [junit4] 2> "Operation aliasprop caused exception:":"org.apache.solr.common.SolrException:org.apache.solr.common.SolrException: Can't modify non-existent alias testModifyPropertiesV2", [junit4] 2> "exception":{ [junit4] 2> "msg":"Can't modify non-existent alias testModifyPropertiesV2", [junit4] 2> "rspCode":400}, [junit4] 2> "error":{ [junit4] 2> "metadata":[ [junit4] 2> "error-class","org.apache.solr.common.SolrException", [junit4] 2> "root-error-class","org.apache.solr.common.SolrException"], [junit4] 2> "msg":"Can't modify non-existent alias testModifyPropertiesV2", [junit4] 2> "code":400}} [junit4] 2> [junit4] 2> 949895 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[816EEC511566DBB2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testModifyPropertiesV2 [junit4] 2> 949896 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :listaliases with params action=LISTALIASES&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 949896 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LISTALIASES&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 949898 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[816EEC511566DBB2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 949898 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection2meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 949911 ERROR (OverseerThreadFactory-3259-thread-4) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: collection2meta operation: delete failed:org.apache.solr.common.SolrException: Collection : collection2meta is part of alias testModifyPropertiesV2 remove or modify the alias before removing this collection. [junit4] 2> at org.apache.solr.cloud.api.collections.DeleteCollectionCmd.call(DeleteCollectionCmd.java:71) [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:252) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192) [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> 949912 INFO (OverseerCollectionConfigSetProcessor-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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> 949914 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection2meta&action=DELETE&wt=javabin&version=2} status=500 QTime=15 [junit4] 2> 949914 ERROR (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall null:org.apache.solr.common.SolrException: Collection : collection2meta is part of alias testModifyPropertiesV2 remove or modify the alias before removing this collection. [junit4] 2> at org.apache.solr.client.solrj.SolrResponse.getException(SolrResponse.java:53) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:258) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:230) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:393) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:339) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:530) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:289) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:149) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 949917 ERROR (OverseerCollectionConfigSetProcessor-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor :org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000006 [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) [junit4] 2> at org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:1330) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$setData$6(SolrZkClient.java:352) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:352) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:540) [junit4] 2> at org.apache.solr.cloud.OverseerTaskQueue.remove(OverseerTaskQueue.java:97) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor.cleanUpWorkQueue(OverseerTaskProcessor.java:326) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor.run(OverseerTaskProcessor.java:202) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AliasIntegrationTest -Dtests.method=testModifyPropertiesV2 -Dtests.seed=816EEC511566DBB2 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ja-JP-u-ca-japanese-x-lvariant-JP -Dtests.timezone=Europe/Belfast -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 5.25s J0 | AliasIntegrationTest.testModifyPropertiesV2 <<< [junit4] > Throwable #1: java.lang.AssertionError: Unexpected status: HTTP/1.1 400 Bad Request [junit4] > at org.apache.solr.cloud.AliasIntegrationTest.assertSuccess(AliasIntegrationTest.java:321) [junit4] > at org.apache.solr.cloud.AliasIntegrationTest.testModifyPropertiesV2(AliasIntegrationTest.java:238) [junit4] > at java.lang.Thread.run(Thread.java:748)Throwable #2: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:39751/solr: Collection : collection2meta is part of alias testModifyPropertiesV2 remove or modify the alias before removing this collection. [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) [junit4] > at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:451) [junit4] > at org.apache.solr.cloud.AliasIntegrationTest.tearDown(AliasIntegrationTest.java:92) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 949922 INFO (OverseerCollectionConfigSetProcessor-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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> 949948 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[816EEC511566DBB2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteAliasWithExistingCollectionName [junit4] 2> 949963 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 949964 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 949969 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection_old&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 949972 INFO (OverseerThreadFactory-3259-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_old [junit4] 2> 950263 INFO (OverseerStateUpdate-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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:39751/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 950287 INFO (OverseerStateUpdate-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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:49100/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 950419 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr ] 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> 950420 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_solr ] 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> 951438 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 951439 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 951474 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951474 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951476 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951476 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951476 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951476 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951477 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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_49100.solr.core.collection_old.shard2.replica_n3' (registry 'solr.core.collection_old.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 951477 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 951477 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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 [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node2/collection_old_shard2_replica_n3], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node2/./collection_old_shard2_replica_n3/data/] [junit4] 2> 951480 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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_39751.solr.core.collection_old.shard1.replica_n1' (registry 'solr.core.collection_old.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 951480 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 951480 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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 [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/collection_old_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/./collection_old_shard1_replica_n1/data/] [junit4] 2> 951531 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951531 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951533 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951533 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951533 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951533 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951534 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951534 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951534 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@7aaf7bd5[collection_old_shard2_replica_n3] main] [junit4] 2> 951535 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a8e7ed5[collection_old_shard1_replica_n1] main] [junit4] 2> 951535 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951536 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951536 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951536 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951537 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951537 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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 1598051249551310848 [junit4] 2> 951540 INFO (searcherExecutor-3280-thread-1-processing-n:127.0.0.1:39751_solr x:collection_old_shard1_replica_n1 c:collection_old s:shard1 r:core_node2) [n:127.0.0.1:39751_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@3a8e7ed5[collection_old_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 951540 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951540 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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 1598051249554456576 [junit4] 2> 951544 INFO (searcherExecutor-3279-thread-1-processing-n:127.0.0.1:49100_solr x:collection_old_shard2_replica_n3 c:collection_old s:shard2 r:core_node4) [n:127.0.0.1:49100_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@7aaf7bd5[collection_old_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 951546 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951551 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951553 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951553 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951553 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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:49100/solr/collection_old_shard2_replica_n3/ [junit4] 2> 951554 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951554 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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:49100/solr/collection_old_shard2_replica_n3/ has no replicas [junit4] 2> 951554 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 951554 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951554 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951555 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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:39751/solr/collection_old_shard1_replica_n1/ [junit4] 2> 951556 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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:49100/solr/collection_old_shard2_replica_n3/ shard2 [junit4] 2> 951556 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951557 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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:39751/solr/collection_old_shard1_replica_n1/ has no replicas [junit4] 2> 951557 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 951559 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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:39751/solr/collection_old_shard1_replica_n1/ shard1 [junit4] 2> 951714 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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> 951714 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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> 951716 INFO (qtp1796266967-10427) [n:127.0.0.1:49100_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=1295 [junit4] 2> 951716 INFO (qtp1916961120-10420) [n:127.0.0.1:39751_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=1296 [junit4] 2> 951718 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 951718 INFO (OverseerCollectionConfigSetProcessor-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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> 951823 INFO (zkCallback-2564-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> 951824 INFO (zkCallback-2566-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> 952718 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection_old&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2749 [junit4] 2> 952719 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection_new&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 952732 INFO (OverseerThreadFactory-3259-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_new [junit4] 2> 952937 INFO (OverseerStateUpdate-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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:39751/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 953139 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr ] 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> 954152 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 954159 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954161 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954162 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954162 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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_39751.solr.core.collection_new.shard1.replica_n1' (registry 'solr.core.collection_new.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@558408a8 [junit4] 2> 954162 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 954162 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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 [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/collection_new_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001/tempDir-001/node1/./collection_new_shard1_replica_n1/data/] [junit4] 2> 954219 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954219 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954220 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954220 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954221 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c96628c[collection_new_shard1_replica_n1] main] [junit4] 2> 954222 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954222 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954223 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954224 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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 1598051252368834560 [junit4] 2> 954226 INFO (searcherExecutor-3289-thread-1-processing-n:127.0.0.1:39751_solr x:collection_new_shard1_replica_n1 c:collection_new s:shard1 r:core_node2) [n:127.0.0.1:39751_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@5c96628c[collection_new_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 954229 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954230 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954230 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954230 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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:39751/solr/collection_new_shard1_replica_n1/ [junit4] 2> 954231 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954231 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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:39751/solr/collection_new_shard1_replica_n1/ has no replicas [junit4] 2> 954231 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 954233 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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:39751/solr/collection_new_shard1_replica_n1/ shard1 [junit4] 2> 954335 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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> 954337 INFO (qtp1916961120-10423) [n:127.0.0.1:39751_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=1197 [junit4] 2> 954338 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 954436 INFO (zkCallback-2566-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> 954735 INFO (OverseerCollectionConfigSetProcessor-73648305009065990-127.0.0.1:39751_solr-n_0000000000) [ ] 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> 955339 INFO (qtp1916961120-10424) [n:127.0.0.1:39751_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection_new&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2619 [junit4] 2> 955347 INFO (qtp1796266967-10432) [n:127.0.0.1:49100_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> 955347 INFO (qtp1796266967-10432) [n:127.0.0.1:49100_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:4&wt=javabin&version=2}{add=[6 (1598051253544288256), 7 (1598051253545336832)]} 0 1 [junit4] 2> 955353 INFO (qtp1916961120-10422) [n:127.0.0.1:39751_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] [...truncated too long message...] unit4] 2> 3 /solr/collections/collection1meta/state.json [junit4] 2> 2 /solr/collections/collection_new/state.json [junit4] 2> 2 /solr/collections/collection_two/state.json [junit4] 2> 2 /solr/collections/collection2meta/state.json [junit4] 2> 2 /solr/collections/collection2/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 75 /solr/collections [junit4] 2> 16 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_816EEC511566DBB2-001 [junit4] 2> Apr 18, 2018 3:01:09 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {a_t=PostingsFormat(name=LuceneFixedGap), id=BlockTreeOrds(blocksize=128)}, docValues:{_version_=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=342, maxMBSortInHeap=7.442994795227689, sim=RandomSimilarity(queryNorm=true): {}, locale=ja-JP-u-ca-japanese-x-lvariant-JP, timezone=Europe/Belfast [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=298231408,total=522190848 [junit4] 2> NOTE: All tests run in this JVM: [DocValuesNotIndexedTest, TestSchemalessBufferedUpdates, TestConfig, TestRTimerTree, UtilsToolTest, TestNumericRangeQuery32, ShufflingReplicaListTransformerTest, PKIAuthenticationIntegrationTest, TestManagedResource, TestPhraseSuggestions, TestNodeLostTrigger, TestJoin, HdfsDirectoryTest, MetricTriggerTest, SolrMetricsIntegrationTest, DeleteStatusTest, CustomHighlightComponentTest, AutoAddReplicasIntegrationTest, SolrCloudReportersTest, TestSolrCoreSnapshots, SubstringBytesRefFilterTest, DistributedTermsComponentTest, DistributedFacetExistsSmallTest, TestOverriddenPrefixQueryForCustomFieldType, TestRuleBasedAuthorizationPlugin, TestImpersonationWithHadoopAuth, CloneFieldUpdateProcessorFactoryTest, CdcrRequestHandlerTest, MoveReplicaTest, HdfsRecoveryZkTest, SolrXmlInZkTest, TestDistributedSearch, TestFilteredDocIdSet, HdfsWriteToMultipleCollectionsTest, TestMacros, SolrRequestParserTest, EchoParamsTest, OpenCloseCoreStressTest, TestWordDelimiterFilterFactory, SpellingQueryConverterTest, HttpTriggerListenerTest, CheckHdfsIndexTest, BigEndianAscendingWordDeserializerTest, HLLUtilTest, PeerSyncWithIndexFingerprintCachingTest, FacetPivotSmallTest, ZkCLITest, LeaderVoteWaitTimeoutTest, TestSlowCompositeReaderWrapper, TestQueryWrapperFilter, TestSubQueryTransformer, TestRemoteStreaming, TestDocTermOrdsUninvertLimit, ZkStateReaderTest, RestoreTriggerStateTest, TestSolr4Spatial2, TestFieldCacheWithThreads, TestCollectionAPIs, TestXmlQParser, SolrPluginUtilsTest, ScheduledTriggerTest, MBeansHandlerTest, HdfsBasicDistributedZk2Test, TestStressInPlaceUpdates, TestHashPartitioner, SynonymTokenizerTest, TestPullReplica, TestReqParamsAPI, TestSolrConfigHandler, SpellCheckCollatorWithCollapseTest, TestPHPSerializedResponseWriter, NodeMutatorTest, TestRandomFaceting, UUIDFieldTest, TestComponentsName, TestCloudSearcherWarming, TestInfoStreamLogging, FullSolrCloudDistribCmdsTest, ParsingFieldUpdateProcessorsTest, ClassificationUpdateProcessorFactoryTest, TestCrossCoreJoin, FieldAnalysisRequestHandlerTest, CoreSorterTest, RollingRestartTest, TestSolrCloudWithKerberosAlt, ConfigSetsAPITest, TestFastOutputStream, SegmentsInfoRequestHandlerTest, DistributedQueryElevationComponentTest, TestDocSet, TolerantUpdateProcessorTest, CdcrBootstrapTest, TestCloudPivotFacet, RequestHandlersTest, SolrJmxReporterCloudTest, TestLMDirichletSimilarityFactory, MetricsHandlerTest, BooleanFieldTest, TestClassicSimilarityFactory, TestJsonRequest, JavabinLoaderTest, CoreAdminRequestStatusTest, DistributedQueryComponentCustomSortTest, TestLuceneMatchVersion, IndexBasedSpellCheckerTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DateRangeFieldTest, SearchHandlerTest, TemplateUpdateProcessorTest, TestManagedSynonymFilterFactory, HttpPartitionTest, TestHalfAndHalfDocValues, TestCollectionAPI, MissingSegmentRecoveryTest, TestRetrieveFieldsOptimizer, AliasIntegrationTest] [junit4] Completed [338/802 (1!)] on J0 in 46.00s, 8 tests, 1 error, 3 skipped <<< FAILURES! [...truncated 48766 lines...] -ecj-javadoc-lint-tests: [mkdir] Created dir: /tmp/ecj1502692724 [ecj-lint] Compiling 895 source files to /tmp/ecj1502692724 [ecj-lint] invalid Class-Path header in manifest of jar file: /x1/jenkins/.ivy2/cache/org.restlet.jee/org.restlet/jars/org.restlet-2.3.0.jar [ecj-lint] invalid Class-Path header in manifest of jar file: /x1/jenkins/.ivy2/cache/org.restlet.jee/org.restlet.ext.servlet/jars/org.restlet.ext.servlet-2.3.0.jar [ecj-lint] ---------- [ecj-lint] 1. WARNING in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/analysis/TokenizerChainTest.java (at line 37) [ecj-lint] TokenizerChain tokenizerChain = new TokenizerChain( [ecj-lint] ^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'tokenizerChain' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 2. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/DeleteReplicaTest.java (at line 31) [ecj-lint] import java.util.function.Supplier; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import java.util.function.Supplier is never used [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 3. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/ForceLeaderTest.java (at line 25) [ecj-lint] import java.util.concurrent.TimeUnit; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import java.util.concurrent.TimeUnit is never used [ecj-lint] ---------- [ecj-lint] 4. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/ForceLeaderTest.java (at line 26) [ecj-lint] import java.util.stream.Collectors; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import java.util.stream.Collectors is never used [ecj-lint] ---------- [ecj-lint] 5. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/ForceLeaderTest.java (at line 32) [ecj-lint] import org.apache.solr.cloud.overseer.OverseerAction; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.cloud.overseer.OverseerAction is never used [ecj-lint] ---------- [ecj-lint] 6. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/ForceLeaderTest.java (at line 38) [ecj-lint] import org.apache.solr.common.cloud.ZkNodeProps; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.common.cloud.ZkNodeProps is never used [ecj-lint] ---------- [ecj-lint] 7. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/ForceLeaderTest.java (at line 39) [ecj-lint] import org.apache.solr.common.cloud.ZkStateReader; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.common.cloud.ZkStateReader is never used [ecj-lint] ---------- [ecj-lint] 8. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/ForceLeaderTest.java (at line 41) [ecj-lint] import org.apache.solr.common.util.Utils; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.common.util.Utils is never used [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 9. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/MoveReplicaTest.java (at line 25) [ecj-lint] import java.util.HashSet; [ecj-lint] ^^^^^^^^^^^^^^^^^ [ecj-lint] The import java.util.HashSet is never used [ecj-lint] ---------- [ecj-lint] 10. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/MoveReplicaTest.java (at line 42) [ecj-lint] import org.apache.solr.common.cloud.CollectionStateWatcher; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.common.cloud.CollectionStateWatcher is never used [ecj-lint] ---------- [ecj-lint] 11. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test/org/apache/solr/cloud/MoveReplicaTest.java (at line 46) [ecj-lint] import org.apache.solr.common.cloud.ZkStateReaderAccessor; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.common.cloud.ZkStateReaderAccessor is never used [ecj-lint] ---------- [ecj-lint] 11 problems (10 errors, 1 warning) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:633: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:101: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build.xml:690: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:2095: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:2128: Compile failed; see the compiler error output for details. Total time: 87 minutes 4 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
