Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1284/ Java: 64bit/jdk-9.0.1 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.AliasIntegrationTest.testModifyMetadataV1 Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([681839F4697E861B:B978873D49D29542]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.cloud.AliasIntegrationTest.checkFooAndBarMeta(AliasIntegrationTest.java:283) at org.apache.solr.cloud.AliasIntegrationTest.testModifyMetadataV1(AliasIntegrationTest.java:251) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 13385 lines...] [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/init-core-data-001 [junit4] 2> 1766033 WARN (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4 [junit4] 2> 1766033 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1766034 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 1766034 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1766034 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001 [junit4] 2> 1766034 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1766035 INFO (Thread-8414) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1766035 INFO (Thread-8414) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1766036 ERROR (Thread-8414) [ ] 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> 1766135 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:39131 [junit4] 2> 1766136 INFO (zkConnectionManagerCallback-3392-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1766139 INFO (jetty-launcher-3389-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1766140 INFO (jetty-launcher-3389-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6af59d8{/solr,null,AVAILABLE} [junit4] 2> 1766140 INFO (jetty-launcher-3389-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@267bd078{/solr,null,AVAILABLE} [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@589d6ff{SSL,[ssl, http/1.1]}{127.0.0.1:39997} [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@142b6d56{SSL,[ssl, http/1.1]}{127.0.0.1:32991} [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-1) [ ] o.e.j.s.Server Started @1767979ms [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-2) [ ] o.e.j.s.Server Started @1767979ms [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39997} [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=32991} [junit4] 2> 1766141 ERROR (jetty-launcher-3389-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1766141 ERROR (jetty-launcher-3389-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-02T21:43:19.775640Z [junit4] 2> 1766141 INFO (jetty-launcher-3389-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-02T21:43:19.775672Z [junit4] 2> 1766142 INFO (zkConnectionManagerCallback-3395-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766142 INFO (zkConnectionManagerCallback-3396-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766142 INFO (jetty-launcher-3389-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1766142 INFO (jetty-launcher-3389-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1766146 INFO (jetty-launcher-3389-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39131/solr [junit4] 2> 1766146 INFO (jetty-launcher-3389-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39131/solr [junit4] 2> 1766147 INFO (zkConnectionManagerCallback-3404-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766147 INFO (zkConnectionManagerCallback-3402-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766147 INFO (zkConnectionManagerCallback-3406-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766148 INFO (zkConnectionManagerCallback-3408-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766156 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1766156 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:32991_solr [junit4] 2> 1766157 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.c.Overseer Overseer (id=72081905279500294-127.0.0.1:32991_solr-n_0000000000) starting [junit4] 2> 1766160 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32991_solr [junit4] 2> 1766160 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1766180 INFO (jetty-launcher-3389-thread-1) [n:127.0.0.1:39997_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1766181 INFO (jetty-launcher-3389-thread-1) [n:127.0.0.1:39997_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1766182 INFO (jetty-launcher-3389-thread-1) [n:127.0.0.1:39997_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39997_solr [junit4] 2> 1766182 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1766182 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1766200 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32991.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1766210 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32991.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1766210 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32991.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1766211 INFO (jetty-launcher-3389-thread-2) [n:127.0.0.1:32991_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node2/. [junit4] 2> 1766225 INFO (jetty-launcher-3389-thread-1) [n:127.0.0.1:39997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39997.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1766235 INFO (jetty-launcher-3389-thread-1) [n:127.0.0.1:39997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39997.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1766236 INFO (jetty-launcher-3389-thread-1) [n:127.0.0.1:39997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39997.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1766237 INFO (jetty-launcher-3389-thread-1) [n:127.0.0.1:39997_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node1/. [junit4] 2> 1766261 INFO (zkConnectionManagerCallback-3414-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766263 INFO (zkConnectionManagerCallback-3418-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1766264 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1766264 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39131/solr ready [junit4] 2> 1766270 INFO (TEST-AliasIntegrationTest.testMetadata-seed#[681839F4697E861B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMetadata [junit4] 2> 1766285 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1766285 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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> 1766289 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_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> 1766290 INFO (OverseerThreadFactory-8207-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta [junit4] 2> 1766394 INFO (OverseerStateUpdate-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] 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:39997/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1766395 INFO (OverseerStateUpdate-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] 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_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:32991/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1766598 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_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_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1766598 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1766609 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&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> 1766609 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1766712 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1766712 INFO (zkCallback-3407-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1766712 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1766712 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1767635 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 1767639 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n2] Schema name=minimal [junit4] 2> 1767641 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1767641 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n2' using configuration from collection collection1meta, trusted=true [junit4] 2> 1767641 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32991.solr.core.collection1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1767641 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1767641 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node2/collection1meta_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node2/./collection1meta_shard2_replica_n2/data/] [junit4] 2> 1767642 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 1767646 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal [junit4] 2> 1767647 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1767647 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 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> 1767648 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39997.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1767648 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1767648 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node1/collection1meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node1/./collection1meta_shard1_replica_n1/data/] [junit4] 2> 1767699 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1767699 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1767700 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1767700 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1767702 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@652cdf18[collection1meta_shard2_replica_n2] main] [junit4] 2> 1767703 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1767703 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1767703 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1767704 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1767705 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1767706 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@400d075c[collection1meta_shard1_replica_n1] main] [junit4] 2> 1767707 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1767707 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1767708 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1767708 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1591327117899988992 [junit4] 2> 1767710 INFO (searcherExecutor-8213-thread-1-processing-n:127.0.0.1:39997_solr x:collection1meta_shard1_replica_n1 s:shard1 c:collection1meta r:core_node3) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@400d075c[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1767710 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1767714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1767714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1767714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39997/solr/collection1meta_shard1_replica_n1/ [junit4] 2> 1767714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1767714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:39997/solr/collection1meta_shard1_replica_n1/ has no replicas [junit4] 2> 1767714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1767716 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:39997/solr/collection1meta_shard1_replica_n1/ shard1 [junit4] 2> 1767717 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1767717 INFO (searcherExecutor-8212-thread-1-processing-n:127.0.0.1:32991_solr x:collection1meta_shard2_replica_n2 s:shard2 c:collection1meta r:core_node4) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] Registered new searcher Searcher@652cdf18[collection1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1767718 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1591327117910474752 [junit4] 2> 1767726 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1767726 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1767726 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:32991/solr/collection1meta_shard2_replica_n2/ [junit4] 2> 1767726 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1767726 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:32991/solr/collection1meta_shard2_replica_n2/ has no replicas [junit4] 2> 1767726 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1767728 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:32991/solr/collection1meta_shard2_replica_n2/ shard2 [junit4] 2> 1767829 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1767829 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1767829 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1767829 INFO (zkCallback-3407-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1767868 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1767878 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1767980 INFO (zkCallback-3407-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1767980 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1767980 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1767980 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1768647 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&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=2038 [junit4] 2> 1768647 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] 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_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2049 [junit4] 2> 1768649 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_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> 1768649 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_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=2360 [junit4] 2> 1768649 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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> 1768651 INFO (OverseerThreadFactory-8207-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2meta [junit4] 2> 1768651 INFO (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1768854 INFO (OverseerStateUpdate-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] 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:39997/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1769056 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1769158 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1769158 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1770061 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 1770065 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770066 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770067 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770067 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39997.solr.core.collection2meta.shard1.replica_n1' (registry 'solr.core.collection2meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1770067 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1770067 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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 [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node1/collection2meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node1/./collection2meta_shard1_replica_n1/data/] [junit4] 2> 1770107 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770107 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770108 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1770108 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1770109 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@58503c2[collection2meta_shard1_replica_n1] main] [junit4] 2> 1770109 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770110 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770110 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770110 INFO (searcherExecutor-8222-thread-1-processing-n:127.0.0.1:39997_solr x:collection2meta_shard1_replica_n1 s:shard1 c:collection2meta r:core_node2) [n:127.0.0.1:39997_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@58503c2[collection2meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1770111 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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 1591327120419717120 [junit4] 2> 1770114 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770114 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770114 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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:39997/solr/collection2meta_shard1_replica_n1/ [junit4] 2> 1770114 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770114 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:39997/solr/collection2meta_shard1_replica_n1/ has no replicas [junit4] 2> 1770114 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770115 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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:39997/solr/collection2meta_shard1_replica_n1/ shard1 [junit4] 2> 1770216 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1770216 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1770265 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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> 1770367 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1770367 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1770739 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_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=1683 [junit4] 2> 1770741 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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> 1770741 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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=2091 [junit4] 2> 1770747 INFO (zkConnectionManagerCallback-3421-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1770748 INFO (TEST-AliasIntegrationTest.testMetadata-seed#[681839F4697E861B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1770749 INFO (TEST-AliasIntegrationTest.testMetadata-seed#[681839F4697E861B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMetadata [junit4] 2> 1770750 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :listaliases with params action=LISTALIASES&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1770750 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_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> 1770751 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletealias with params name=meta1&action=DELETEALIAS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1770752 INFO (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1770753 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=meta1&action=DELETEALIAS&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 1770754 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletealias with params name=meta3&action=DELETEALIAS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1770755 INFO (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1770756 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=meta3&action=DELETEALIAS&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 1770756 ERROR (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] 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.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 1770756 INFO (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1770756 INFO (TEST-AliasIntegrationTest.testMetadata-seed#[681839F4697E861B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1770757 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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> 1770760 INFO (OverseerThreadFactory-8207-thread-5-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 1770761 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=null [junit4] 2> 1770761 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@23b9cc2c: rootName = solr_39997, domain = solr.core.collection2meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection2meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@74b15418 [junit4] 2> 1770770 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@3d08936 [junit4] 2> 1770770 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=63998262 [junit4] 2> 1770770 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection2meta.shard1.leader, tag=63998262 [junit4] 2> 1770772 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=collection2meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=11 [junit4] 2> 1771476 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection2meta&action=DELETE&wt=javabin&version=2} status=0 QTime=719 [junit4] 2> 1771477 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection1meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1771478 INFO (OverseerThreadFactory-8207-thread-5-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 1771478 INFO (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1771478 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=null [junit4] 2> 1771478 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=null [junit4] 2> 1771478 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@37fa4843: rootName = solr_32991, domain = solr.core.collection1meta.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.collection1meta.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@45b67104 [junit4] 2> 1771478 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f9ae72: rootName = solr_39997, domain = solr.core.collection1meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection1meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@67d7380e [junit4] 2> 1771491 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@54837cd4 [junit4] 2> 1771491 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=1417903316 [junit4] 2> 1771491 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard1.leader, tag=1417903316 [junit4] 2> 1771492 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@4d6dfb35 [junit4] 2> 1771493 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=1299053365 [junit4] 2> 1771493 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard2.leader, tag=1299053365 [junit4] 2> 1771494 INFO (qtp1810739546-21034) [n:127.0.0.1:39997_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=collection1meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=15 [junit4] 2> 1771495 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=collection1meta_shard2_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=16 [junit4] 2> 1772199 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection1meta&action=DELETE&wt=javabin&version=2} status=0 QTime=722 [junit4] 2> 1772209 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[681839F4697E861B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteAliasWithExistingCollectionName [junit4] 2> 1772210 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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> 1772211 INFO (OverseerThreadFactory-8207-thread-5-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_old [junit4] 2> 1772211 INFO (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1772415 INFO (OverseerStateUpdate-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_old", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection_old_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:32991/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1772416 INFO (OverseerStateUpdate-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_old", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection_old_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:39997/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1772617 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&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> 1772617 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_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_n2&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1772719 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1772719 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1772719 INFO (zkCallback-3407-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1772719 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1773622 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 1773622 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 1773663 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.s.IndexSchema [collection_old_shard2_replica_n2] Schema name=minimal [junit4] 2> 1773663 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.s.IndexSchema [collection_old_shard1_replica_n1] Schema name=minimal [junit4] 2> 1773664 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1773664 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1773664 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection_old_shard2_replica_n2' using configuration from collection collection_old, trusted=true [junit4] 2> 1773664 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 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> 1773665 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32991.solr.core.collection_old.shard1.replica_n1' (registry 'solr.core.collection_old.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1773665 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39997.solr.core.collection_old.shard2.replica_n2' (registry 'solr.core.collection_old.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1773665 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1773665 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1773665 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore [[collection_old_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node2/collection_old_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node2/./collection_old_shard1_replica_n1/data/] [junit4] 2> 1773665 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.SolrCore [[collection_old_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node1/collection_old_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node1/./collection_old_shard2_replica_n2/data/] [junit4] 2> 1773702 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1773702 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 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> 1773703 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1773703 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1773705 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7caf942e[collection_old_shard1_replica_n1] main] [junit4] 2> 1773705 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1773706 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 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> 1773706 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1773707 INFO (searcherExecutor-8228-thread-1-processing-n:127.0.0.1:32991_solr x:collection_old_shard1_replica_n1 s:shard1 c:collection_old r:core_node3) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore [collection_old_shard1_replica_n1] Registered new searcher Searcher@7caf942e[collection_old_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1773707 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1591327124190396416 [junit4] 2> 1773710 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1773710 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1773710 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:32991/solr/collection_old_shard1_replica_n1/ [junit4] 2> 1773710 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1773710 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:32991/solr/collection_old_shard1_replica_n1/ has no replicas [junit4] 2> 1773710 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1773712 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:32991/solr/collection_old_shard1_replica_n1/ shard1 [junit4] 2> 1773714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1773714 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1773715 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1773716 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1773717 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@74e511c5[collection_old_shard2_replica_n2] main] [junit4] 2> 1773718 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1773718 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1773719 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1773719 INFO (searcherExecutor-8227-thread-1-processing-n:127.0.0.1:39997_solr x:collection_old_shard2_replica_n2 s:shard2 c:collection_old r:core_node4) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.SolrCore [collection_old_shard2_replica_n2] Registered new searcher Searcher@74e511c5[collection_old_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1773719 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1591327124202979328 [junit4] 2> 1773723 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1773723 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1773723 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39997/solr/collection_old_shard2_replica_n2/ [junit4] 2> 1773723 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1773723 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:39997/solr/collection_old_shard2_replica_n2/ has no replicas [junit4] 2> 1773723 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1773724 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:39997/solr/collection_old_shard2_replica_n2/ shard2 [junit4] 2> 1773825 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1773825 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1773825 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1773825 INFO (zkCallback-3407-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1773862 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1773863 INFO (qtp1496886007-21023) [n:127.0.0.1:32991_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&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=1246 [junit4] 2> 1773875 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1773876 INFO (qtp1810739546-21037) [n:127.0.0.1:39997_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 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_n2&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1259 [junit4] 2> 1773877 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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> 1773977 INFO (zkCallback-3405-thread-1-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1773977 INFO (zkCallback-3405-thread-2-processing-n:127.0.0.1:39997_solr) [n:127.0.0.1:39997_solr ] 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> 1773977 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1773977 INFO (zkCallback-3407-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1774212 INFO (OverseerCollectionConfigSetProcessor-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000012 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1774877 INFO (qtp1496886007-21031) [n:127.0.0.1:32991_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=2667 [junit4] 2> 1774878 INFO (qtp1496886007-21033) [n:127.0.0.1:32991_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> 1774879 INFO (OverseerThreadFactory-8207-thread-5-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_new [junit4] 2> 1775083 INFO (OverseerStateUpdate-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_new", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection_new_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:32991/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1775284 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1775386 INFO (zkCallback-3407-thread-2-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1775386 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] 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> 1776291 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 1776335 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776337 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776337 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776337 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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_32991.solr.core.collection_new.shard1.replica_n1' (registry 'solr.core.collection_new.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c332ffb [junit4] 2> 1776337 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776338 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore [[collection_new_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node2/collection_new_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001/tempDir-001/node2/./collection_new_shard1_replica_n1/data/] [junit4] 2> 1776399 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776399 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776400 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776400 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776402 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ab442c[collection_new_shard1_replica_n1] main] [junit4] 2> 1776403 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776405 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776406 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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> 1776407 INFO (searcherExecutor-8237-thread-1-processing-n:127.0.0.1:32991_solr x:collection_new_shard1_replica_n1 s:shard1 c:collection_new r:core_node2) [n:127.0.0.1:32991_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@3ab442c[collection_new_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1776407 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_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 1591327127021551616 [junit4] 2> 1776412 INFO (qtp1496886007-21025) [n:127.0.0.1:32991_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shar [...truncated too long message...] jetty / com.codahale.metrics.MetricRegistry@3d3a3273 [junit4] 2> 1826144 INFO (jetty-closer-3390-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:32991_solr [junit4] 2> 1826144 INFO (jetty-closer-3390-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1826144 INFO (zkCallback-3407-thread-1-processing-n:127.0.0.1:32991_solr) [n:127.0.0.1:32991_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 1826148 INFO (jetty-closer-3390-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72081905279500294-127.0.0.1:32991_solr-n_0000000000) closing [junit4] 2> 1826148 INFO (OverseerStateUpdate-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:32991_solr [junit4] 2> 1826149 WARN (OverseerAutoScalingTriggerThread-72081905279500294-127.0.0.1:32991_solr-n_0000000000) [n:127.0.0.1:32991_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1826150 INFO (jetty-closer-3390-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6af59d8{/solr,null,UNAVAILABLE} [junit4] 2> 1826150 INFO (jetty-closer-3390-thread-1) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1826150 ERROR (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1826150 INFO (SUITE-AliasIntegrationTest-seed#[681839F4697E861B]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39131 39131 [junit4] 2> 1831175 INFO (Thread-8414) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39131 39131 [junit4] 2> 1831175 WARN (Thread-8414) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 18 /solr/aliases.json [junit4] 2> 16 /solr/configs/conf [junit4] 2> 8 /solr/collections/collection2meta/terms/shard1 [junit4] 2> 8 /solr/collections/collection1meta/terms/shard2 [junit4] 2> 8 /solr/collections/collection1meta/terms/shard1 [junit4] 2> 3 /solr/collections/testErrorChecks-collection/terms/shard2 [junit4] 2> 2 /solr/collections/collection_new/terms/shard1 [junit4] 2> 2 /solr/collections/collection2/terms/shard1 [junit4] 2> 2 /solr/collections/collection_two/terms/shard1 [junit4] 2> 2 /solr/collections/collection_one/terms/shard1 [junit4] 2> 2 /solr/collections/collection_one/terms/shard2 [junit4] 2> 2 /solr/collections/collection_old/terms/shard2 [junit4] 2> 2 /solr/collections/collection_old/terms/shard1 [junit4] 2> 2 /solr/collections/testErrorChecks-collection/terms/shard1 [junit4] 2> 2 /solr/security.json [junit4] 2> 2 /solr/collections/collection1/terms/shard1 [junit4] 2> 2 /solr/collections/collection1/terms/shard2 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 193 /solr/aliases.json [junit4] 2> 44 /solr/collections/collection1meta/state.json [junit4] 2> 24 /solr/collections/collection2meta/state.json [junit4] 2> 18 /solr/clusterprops.json [junit4] 2> 18 /solr/clusterstate.json [junit4] 2> 11 /solr/collections/collection_one/state.json [junit4] 2> 11 /solr/collections/collection1/state.json [junit4] 2> 11 /solr/collections/collection_old/state.json [junit4] 2> 11 /solr/collections/testErrorChecks-collection/state.json [junit4] 2> 6 /solr/collections/collection_new/state.json [junit4] 2> 6 /solr/collections/collection_two/state.json [junit4] 2> 6 /solr/collections/collection2/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 189 /solr/collections [junit4] 2> 18 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_681839F4697E861B-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {a_t=FST50, id=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1828, maxMBSortInHeap=5.6110150314378435, sim=RandomSimilarity(queryNorm=false): {}, locale=en-BE, timezone=EST5EDT [junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=8,threads=1,free=141344576,total=358612992 [junit4] 2> NOTE: All tests run in this JVM: [TestConfigOverlay, DistributedTermsComponentTest, TestRandomFaceting, ConjunctionSolrSpellCheckerTest, TestDistributedSearch, UUIDFieldTest, TestDocumentBuilder, TestLocalFSCloudBackupRestore, TestSolrQueryParser, TestWordDelimiterFilterFactory, AnalysisErrorHandlingTest, HdfsCollectionsAPIDistributedZkTest, SolrCLIZkUtilsTest, SpellCheckCollatorTest, TestDocTermOrds, MoveReplicaTest, TestPolicyCloud, TestLMDirichletSimilarityFactory, TestLeaderElectionZkExpiry, TestPKIAuthenticationPlugin, TestPointFields, TestBlendedInfixSuggestions, HighlighterConfigTest, TestSolrXml, TestStressInPlaceUpdates, JavabinLoaderTest, SolrMetricsIntegrationTest, FullHLLTest, ParsingFieldUpdateProcessorsTest, SolrRequestParserTest, OutputWriterTest, TestPayloadCheckQParserPlugin, TestCopyFieldCollectionResource, TestDelegationWithHadoopAuth, AddSchemaFieldsUpdateProcessorFactoryTest, OpenExchangeRatesOrgProviderTest, MetricsHandlerTest, CheckHdfsIndexTest, TestDocSet, BadIndexSchemaTest, HdfsBasicDistributedZk2Test, BlockJoinFacetRandomTest, TestEmbeddedSolrServerSchemaAPI, BasicDistributedZkTest, TestNumericTerms64, JSONWriterTest, TestShardHandlerFactory, CloudExitableDirectoryReaderTest, CursorMarkTest, SuggestComponentTest, TestDynamicLoading, TestHighFrequencyDictionaryFactory, TestCloudSchemaless, DistribDocExpirationUpdateProcessorTest, ReplicationFactorTest, CustomHighlightComponentTest, LeaderElectionContextKeyTest, DistributedFacetPivotSmallAdvancedTest, PluginInfoTest, SuggesterWFSTTest, TestSolrQueryResponse, ZkSolrClientTest, TestRTimerTree, TestConfig, FullSolrCloudDistribCmdsTest, TestSSLRandomization, TestSubQueryTransformerCrossCore, TestPullReplica, SolrJmxReporterCloudTest, TestCrossCoreJoin, ShardRoutingTest, TestLegacyNumericUtils, TestCollectionAPIs, SolrIndexSplitterTest, SolrPluginUtilsTest, TestCollectionAPI, TestLegacyNumericRangeQueryBuilder, SolrCoreMetricManagerTest, TestHttpShardHandlerFactory, MBeansHandlerTest, TestIBSimilarityFactory, TestComputePlanAction, SolrIndexConfigTest, TestConfigReload, TestHashPartitioner, TestOmitPositions, TestXmlQParserPlugin, TermVectorComponentDistributedTest, RuleEngineTest, TestSlowCompositeReaderWrapper, TestGraphTermsQParserPlugin, TestExceedMaxTermLength, TestJoin, ZkCLITest, TestFieldCollectionResource, TestSimpleQParserPlugin, DocumentAnalysisRequestHandlerTest, TestRecoveryHdfs, NumberUtilsTest, TestInitParams, NodeLostTriggerTest, CloneFieldUpdateProcessorFactoryTest, TestZkAclsWithHadoopAuth, TestSort, FacetPivotSmallTest, ChaosMonkeySafeLeaderWithPullReplicasTest, ActionThrottleTest, SimpleFacetsTest, TestSolrCloudWithKerberosAlt, HdfsDirectoryFactoryTest, TestMergePolicyConfig, DeleteShardTest, TestManagedSchema, TestSchemaSimilarityResource, CdcrBootstrapTest, TestStressUserVersions, AutoAddReplicasIntegrationTest, TestRandomCollapseQParserPlugin, BinaryUpdateRequestHandlerTest, AnalyticsMergeStrategyTest, TestCloudJSONFacetJoinDomain, TestMiniSolrCloudClusterSSL, SubstringBytesRefFilterTest, RAMDirectoryFactoryTest, DistributedFacetExistsSmallTest, DefaultValueUpdateProcessorTest, TestCustomDocTransformer, BasicZkTest, RecoveryZkTest, TestReplicationHandler, DistributedSpellCheckComponentTest, TestRealTimeGet, TestStressVersions, StatsComponentTest, TestFiltering, BasicFunctionalityTest, DirectUpdateHandlerTest, HighlighterTest, DistributedQueryElevationComponentTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SuggesterFSTTest, CoreAdminHandlerTest, TestFoldingMultitermQuery, TestStressLucene, TestTrie, SpatialFilterTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestQueryUtils, TestWriterPerf, DirectSolrSpellCheckerTest, TestQueryTypes, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, TestCSVResponseWriter, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, TestPHPSerializedResponseWriter, TestComponentsName, SearchHandlerTest, TestLFUCache, SOLR749Test, UpdateParamsTest, CopyFieldTest, TestStressRecovery, PrimUtilsTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, TestSolrJ, TestUtils, ZkNodePropsTest, SliceStateTest, FileUtilsTest, CursorPagingTest, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestLuceneIndexBackCompat, TestEmbeddedSolrServerConstructors, AddReplicaTest, AliasIntegrationTest] [junit4] Completed [517/774 (1!)] on J0 in 65.15s, 8 tests, 1 failure <<< FAILURES! [...truncated 41830 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org