From dev-return-364286-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Tue Aug 6 02:38:11 2019 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [207.244.88.153]) by mx-eu-01.ponee.io (Postfix) with SMTP id 3F791180181 for ; Tue, 6 Aug 2019 04:38:10 +0200 (CEST) Received: (qmail 42849 invoked by uid 500); 6 Aug 2019 02:38:08 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 42839 invoked by uid 99); 6 Aug 2019 02:38:07 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 06 Aug 2019 02:38:07 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id 19681C012B for ; Tue, 6 Aug 2019 02:38:07 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.297 X-Spam-Level: X-Spam-Status: No, score=-0.297 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, NUMERIC_HTTP_ADDR=0.001, RCVD_IN_DNSWL_MED=-2.3, SPF_HELO_NONE=0.001, SPF_PASS=-0.001, URIBL_BLOCKED=0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-ec2-va.apache.org ([10.40.0.8]) by localhost (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id BkzWw4lR3Z8m for ; Tue, 6 Aug 2019 02:38:01 +0000 (UTC) Received-SPF: Pass (mailfrom) identity=mailfrom; client-ip=88.99.242.108; helo=serv1.sd-datasolutions.de; envelope-from=jenkins@thetaphi.de; receiver= Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-ec2-va.apache.org (ASF Mail Server at mx1-ec2-va.apache.org) with ESMTP id A8084BC7B3 for ; Tue, 6 Aug 2019 02:38:00 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id 43EF9108019F for ; Tue, 6 Aug 2019 02:37:59 +0000 (UTC) Date: Tue, 6 Aug 2019 02:36:17 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1615117556.19.1565059079279.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-8.2-Linux (32bit/jdk1.8.0_201) - Build # 525 - Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_18_1698188135.1565059079216" X-Jenkins-Job: Lucene-Solr-8.2-Linux X-Jenkins-Result: UNSTABLE ------=_Part_18_1698188135.1565059079216 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.2-Linux/525/ Java: 32bit/jdk1.8.0_201 -server -XX:+UseG1GC 1 tests failed. FAILED: org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI Error Message: should be a routed alias Stack Trace: java.lang.AssertionError: should be a routed alias at __randomizedtesting.SeedInfo.seed([921035B7799D9533:8DC7A99B0A966C78]:0) at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.assertTrue(Assert.java:41) at org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI(AliasIntegrationTest.java:315) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13308 lines...] [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/init-core-data-001 [junit4] 2> 83552 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001 [junit4] 2> 83552 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 83554 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 83554 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 83654 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:42303 [junit4] 2> 83654 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42303 [junit4] 2> 83654 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42303 [junit4] 2> 83656 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 83657 WARN (NIOWorkerThread-2) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0: ZooKeeperServer not running [junit4] 2> 85137 INFO (zkConnectionManagerCallback-267-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 85137 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 85145 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 85148 INFO (zkConnectionManagerCallback-269-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 85148 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 85153 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 85160 INFO (zkConnectionManagerCallback-271-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 85160 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 85266 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 85266 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 85266 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 85266 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 85266 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 85266 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 85266 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09 [junit4] 2> 85266 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09 [junit4] 2> 85272 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 85272 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 85272 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 85272 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 85272 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 85272 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 85273 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a5c0bf{/solr,null,AVAILABLE} [junit4] 2> 85273 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e56f46{/solr,null,AVAILABLE} [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@15f8886{SSL,[ssl, http/1.1]}{127.0.0.1:38485} [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server Started @85329ms [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38485} [junit4] 2> 85274 ERROR (jetty-launcher-272-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.1 [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-08-06T01:27:31.619Z [junit4] 2> 85281 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 85281 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@bea459{SSL,[ssl, http/1.1]}{127.0.0.1:34659} [junit4] 2> 85281 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server Started @85337ms [junit4] 2> 85281 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34659} [junit4] 2> 85282 ERROR (jetty-launcher-272-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.1 [junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-08-06T01:27:31.627Z [junit4] 2> 85284 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 85289 INFO (zkConnectionManagerCallback-274-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 85289 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 85293 INFO (zkConnectionManagerCallback-276-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 85293 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 85294 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 85294 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 85770 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 85770 WARN (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 85771 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19a3a14[provider=null,keyStore=null,trustStore=null] [junit4] 2> 85771 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19a3a14[provider=null,keyStore=null,trustStore=null] [junit4] 2> 85775 WARN (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 85776 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@155c782[provider=null,keyStore=null,trustStore=null] [junit4] 2> 85776 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@155c782[provider=null,keyStore=null,trustStore=null] [junit4] 2> 85777 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42303/solr [junit4] 2> 85781 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 85783 INFO (zkConnectionManagerCallback-284-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 85783 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 85886 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 85904 INFO (zkConnectionManagerCallback-286-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 85904 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 85989 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38485_solr [junit4] 2> 85989 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) starting [junit4] 2> 86001 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 86005 INFO (zkConnectionManagerCallback-293-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86005 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 86008 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42303/solr ready [junit4] 2> 86023 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38485_solr [junit4] 2> 86029 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38485_solr [junit4] 2> 86041 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 86045 INFO (zkCallback-292-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 86088 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 86175 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 86186 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 86186 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 86187 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/. [junit4] 2> 86388 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 86388 WARN (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 86394 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43dc03[provider=null,keyStore=null,trustStore=null] [junit4] 2> 86394 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43dc03[provider=null,keyStore=null,trustStore=null] [junit4] 2> 86398 WARN (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 86398 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@40b7fa[provider=null,keyStore=null,trustStore=null] [junit4] 2> 86398 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@40b7fa[provider=null,keyStore=null,trustStore=null] [junit4] 2> 86399 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42303/solr [junit4] 2> 86401 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 86402 INFO (zkConnectionManagerCallback-300-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86402 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 86504 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 86506 INFO (zkConnectionManagerCallback-302-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86506 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 86509 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 86512 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34659_solr as DOWN [junit4] 2> 86513 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 86513 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34659_solr [junit4] 2> 86518 INFO (zkCallback-292-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 86518 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 86522 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 86525 INFO (zkConnectionManagerCallback-309-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86525 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 86526 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 86527 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 86529 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42303/solr ready [junit4] 2> 86549 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 86572 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 86582 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 86582 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 86583 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/. [junit4] 2> 86617 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 86619 INFO (zkConnectionManagerCallback-315-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86619 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 86620 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 86621 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42303/solr ready [junit4] 2> 86750 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 86753 INFO (OverseerThreadFactory-304-thread-1-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta [junit4] 2> 86866 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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:38485/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 86867 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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:34659/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 87078 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] 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> 87078 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 87138 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] 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> 88100 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1 [junit4] 2> 88108 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88110 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88110 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88111 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 88111 INFO (qtp10842137-935) [n:127.0.0.1:38485_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-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/collection1meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/./collection1meta_shard1_replica_n1/data/] [junit4] 2> 88153 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1 [junit4] 2> 88163 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88164 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88164 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88166 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 88166 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 88167 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bd191[collection1meta_shard1_replica_n1] main] [junit4] 2> 88168 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88168 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88169 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.core.collection1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 88169 INFO (qtp21250618-932) [n:127.0.0.1:34659_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-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/collection1meta_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/./collection1meta_shard2_replica_n2/data/] [junit4] 2> 88173 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88173 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88174 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88174 INFO (qtp10842137-935) [n:127.0.0.1:38485_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 1641078959132114944 [junit4] 2> 88179 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node3=0}, version=0} [junit4] 2> 88180 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1 [junit4] 2> 88181 INFO (searcherExecutor-316-thread-1-processing-n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node3) [n:127.0.0.1:38485_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@1bd191[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_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:38485/solr/collection1meta_shard1_replica_n1/ [junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:38485/solr/collection1meta_shard1_replica_n1/ has no replicas [junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/72067346309447686-core_node3-n_0000000000 [junit4] 2> 88186 INFO (qtp10842137-935) [n:127.0.0.1:38485_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:38485/solr/collection1meta_shard1_replica_n1/ shard1 [junit4] 2> 88253 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88253 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88254 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 88255 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 88256 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c73f40[collection1meta_shard2_replica_n2] main] [junit4] 2> 88258 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88258 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88258 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88259 INFO (qtp21250618-932) [n:127.0.0.1:34659_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 1641078959221243904 [junit4] 2> 88262 INFO (searcherExecutor-321-thread-1-processing-n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:34659_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@c73f40[collection1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 88266 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 88266 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2 [junit4] 2> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_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:34659/solr/collection1meta_shard2_replica_n2/ [junit4] 2> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88270 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:34659/solr/collection1meta_shard2_replica_n2/ has no replicas [junit4] 2> 88270 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/72067346309447689-core_node4-n_0000000000 [junit4] 2> 88271 INFO (qtp21250618-932) [n:127.0.0.1:34659_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:34659/solr/collection1meta_shard2_replica_n2/ shard2 [junit4] 2> 88373 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 88373 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 88374 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 88375 INFO (qtp21250618-932) [n:127.0.0.1:34659_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> 88480 INFO (zkCallback-285-thread-2) [ ] 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> 88480 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 88480 INFO (zkCallback-301-thread-2) [ ] 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> 88480 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 88627 INFO (qtp21250618-932) [n:127.0.0.1:34659_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=1489 [junit4] 2> 88632 INFO (qtp10842137-935) [n:127.0.0.1:38485_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=1554 [junit4] 2> 88638 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 88638 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1887 [junit4] 2> 88641 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 88643 INFO (OverseerThreadFactory-304-thread-2-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2meta [junit4] 2> 88643 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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> 88861 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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:34659/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 89064 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] 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> 90083 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1 [junit4] 2> 90090 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90092 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90092 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90093 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.core.collection2meta.shard1.replica_n1' (registry 'solr.core.collection2meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 90094 INFO (qtp21250618-934) [n:127.0.0.1:34659_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-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/collection2meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/./collection2meta_shard1_replica_n1/data/] [junit4] 2> 90148 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90149 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90150 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 90150 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 90152 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@63aff[collection2meta_shard1_replica_n1] main] [junit4] 2> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_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 1641078961208295424 [junit4] 2> 90158 INFO (searcherExecutor-326-thread-1-processing-n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 c:collection2meta s:shard1 r:core_node2) [n:127.0.0.1:34659_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@63aff[collection2meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 90175 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 90176 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2meta/leaders/shard1 [junit4] 2> 90186 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_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:34659/solr/collection2meta_shard1_replica_n1/ [junit4] 2> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34659/solr/collection2meta_shard1_replica_n1/ has no replicas [junit4] 2> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2meta/leaders/shard1/leader after winning as /collections/collection2meta/leader_elect/shard1/election/72067346309447689-core_node2-n_0000000000 [junit4] 2> 90189 INFO (qtp21250618-934) [n:127.0.0.1:34659_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:34659/solr/collection2meta_shard1_replica_n1/ shard1 [junit4] 2> 90291 INFO (zkCallback-301-thread-2) [ ] 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> 90291 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90298 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 90307 INFO (qtp21250618-934) [n:127.0.0.1:34659_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=1243 [junit4] 2> 90311 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 90400 INFO (zkCallback-285-thread-2) [ ] 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> 90400 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90400 INFO (zkCallback-301-thread-2) [ ] 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> 90401 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1760 [junit4] 2> 90418 INFO (zkCallback-301-thread-3) [ ] 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> 90442 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2 [junit4] 2> 90443 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2 [junit4] 2> 90443 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2 [junit4] 2> 90443 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1 [junit4] 2> 90444 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1 [junit4] 2> 90444 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1 [junit4] 2> 90451 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with params name=testClusterStateProviderAPI&action=ALIASPROP&property.foo=baz&property.bar=bam&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 90472 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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> 90475 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=testClusterStateProviderAPI&action=ALIASPROP&property.foo=baz&property.bar=bam&wt=javabin&version=2} status=0 QTime=23 [junit4] 2> 90477 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with params property.router.foo=baz&name=testClusterStateProviderAPI&action=ALIASPROP&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 90488 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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> 90491 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={property.router.foo=baz&name=testClusterStateProviderAPI&action=ALIASPROP&wt=javabin&version=2} status=0 QTime=13 [junit4] 2> 90494 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 90498 INFO (qtp10842137-931) [n:127.0.0.1:38485_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> 90529 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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> 90537 INFO (OverseerThreadFactory-304-thread-5-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 90542 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=null [junit4] 2> 90542 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16ca33b: rootName = solr_34659, 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@79ec9e [junit4] 2> 90560 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@766d5b [junit4] 2> 90560 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=766d5b [junit4] 2> 90560 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection2meta.shard1.leader, tag=766d5b [junit4] 2> 90585 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={}, version=1} [junit4] 2> 90587 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection2meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=48 [junit4] 2> 90693 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90693 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90693 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90693 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90693 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90706 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection2meta&action=DELETE&wt=javabin&version=2} status=0 QTime=207 [junit4] 2> 90707 INFO (qtp10842137-935) [n:127.0.0.1:38485_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> 90710 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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> 90710 INFO (OverseerThreadFactory-304-thread-5-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 90724 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=null [junit4] 2> 90724 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1bab1ed: rootName = solr_38485, 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@b940d1 [junit4] 2> 90724 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=null [junit4] 2> 90724 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8a6d90: rootName = solr_34659, 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@18340fd [junit4] 2> 90746 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@354f2c [junit4] 2> 90746 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=354f2c [junit4] 2> 90746 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard2.leader, tag=354f2c [junit4] 2> 90749 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={}, version=1} [junit4] 2> 90750 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard2_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=26 [junit4] 2> 90755 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@17c37d4 [junit4] 2> 90755 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=17c37d4 [junit4] 2> 90755 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard1.leader, tag=17c37d4 [junit4] 2> 90765 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={}, version=1} [junit4] 2> 90768 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=45 [junit4] 2> 90871 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90871 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90871 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90871 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 90878 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection1meta&action=DELETE&wt=javabin&version=2} status=0 QTime=171 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AliasIntegrationTest -Dtests.method=testClusterStateProviderAPI -Dtests.seed=921035B7799D9533 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=Asia/Katmandu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 4.27s J1 | AliasIntegrationTest.testClusterStateProviderAPI <<< [junit4] > Throwable #1: java.lang.AssertionError: should be a routed alias [junit4] > at __randomizedtesting.SeedInfo.seed([921035B7799D9533:8DC7A99B0A966C78]:0) [junit4] > at org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI(AliasIntegrationTest.java:315) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 90949 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 90966 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=16 [junit4] 2> 90969 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 90981 INFO (OverseerThreadFactory-304-thread-5-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta [junit4] 2> 90981 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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> 91197 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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:34659/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 91199 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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:38485/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 91401 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard2_replica_n2 ] 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> 91401 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr x:collection1meta_shard1_replica_n1 ] 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> 92426 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1 [junit4] 2> 92426 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1 [junit4] 2> 92434 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92524 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92524 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92524 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92525 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 92525 INFO (qtp21250618-934) [n:127.0.0.1:34659_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-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/collection1meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/./collection1meta_shard1_replica_n1/data/] [junit4] 2> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.core.collection1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236 [junit4] 2> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_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-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/collection1meta_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/./collection1meta_shard2_replica_n2/data/] [junit4] 2> 92606 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92607 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92608 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 92608 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 92611 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c13338[collection1meta_shard1_replica_n1] main] [junit4] 2> 92612 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92612 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92613 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92613 INFO (qtp21250618-934) [n:127.0.0.1:34659_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 1641078963786743808 [junit4] 2> 92621 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92621 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92622 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 92622 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 92624 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d1905f[collection1meta_shard2_replica_n2] main] [junit4] 2> 92625 INFO (searcherExecutor-331-thread-1-processing-n:127.0.0.1:34659_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node3) [n:127.0.0.1:34659_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@1c13338[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 92627 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node3=0}, version=0} [junit4] 2> 92627 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1 [junit4] 2> 92628 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92629 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92629 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92629 INFO (qtp10842137-933) [n:127.0.0.1:38485_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 1641078963803521024 [junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_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:34659/solr/collection1meta_shard1_replica_n1/ [junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34659/solr/collection1meta_shard1_replica_n1/ has no replicas [junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/72067346309447689-core_node3-n_0000000000 [junit4] 2> 92638 INFO (searcherExecutor-332-thread-1-processing-n:127.0.0.1:38485_solr x:collection1meta_shard2_replica_n2 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:38485_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@1d1905f[collection1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 92647 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 92647 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2 [junit4] 2> 92649 INFO (qtp21250618-934) [n:127.0.0.1:34659_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:34659/solr/collection1meta_shard1_replica_n1/ shard1 [junit4] 2> 92651 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92651 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92651 INFO (qtp10842137-933) [n:127.0.0.1:38485_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:38485/solr/collection1meta_shard2_replica_n2/ [junit4] 2> 92652 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92652 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:38485/solr/collection1meta_shard2_replica_n2/ has no replicas [junit4] 2> 92652 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/72067346309447686-core_node4-n_0000000000 [junit4] 2> 92653 INFO (qtp10842137-933) [n:127.0.0.1:38485_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:38485/solr/collection1meta_shard2_replica_n2/ shard2 [junit4] 2> 92755 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 92755 INFO (zkCallback-285-thread-2) [ ] 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> 92756 INFO (qtp10842137-933) [n:127.0.0.1:38485_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> 92761 INFO (zkCallback-301-thread-2) [ ] 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> 92761 INFO (zkCallback-301-thread-3) [ ] 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> 92762 INFO (qtp21250618-934) [n:127.0.0.1:34659_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> 92865 INFO (zkCallback-285-thread-2) [ ] 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> 92865 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 92865 INFO (zkCallback-285-thread-3) [ ] 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> 92873 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:Sy [...truncated too long message...] d2 [junit4] 2> 143801 INFO (jetty-closer-390-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 143801 INFO (jetty-closer-390-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19f47c4: rootName = solr_38485, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@134823a [junit4] 2> 143801 INFO (jetty-closer-390-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 143841 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 143841 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2378c0: rootName = solr_34659, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@13592bb [junit4] 2> 143846 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 143846 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1232691: rootName = solr_34659, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@16407d2 [junit4] 2> 143848 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 143848 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@49744a: rootName = solr_34659, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@134823a [junit4] 2> 143848 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 143864 INFO (closeThreadPool-397-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) closing [junit4] 2> 143910 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38485_solr [junit4] 2> 143953 INFO (OverseerAutoScalingTriggerThread-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 143954 INFO (closeThreadPool-397-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) closing [junit4] 2> 144056 INFO (jetty-closer-390-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) closing [junit4] 2> 144061 INFO (jetty-closer-390-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@15f8886{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 144061 INFO (jetty-closer-390-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1e56f46{/solr,null,UNAVAILABLE} [junit4] 2> 144061 INFO (jetty-closer-390-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 144157 INFO (jetty-closer-390-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@bea459{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 144157 INFO (jetty-closer-390-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a5c0bf{/solr,null,UNAVAILABLE} [junit4] 2> 144158 INFO (jetty-closer-390-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 144158 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer. [junit4] 2> 144369 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 28 /solr/collections/collection1meta/state.json [junit4] 2> 22 /solr/aliases.json [junit4] 2> 21 /solr/collections/collection2meta/state.json [junit4] 2> 18 /solr/configs/conf [junit4] 2> 15 /solr/collections/collection1meta/terms/shard1 [junit4] 2> 14 /solr/collections/collection1meta/terms/shard2 [junit4] 2> 13 /solr/collections/collection2meta/terms/shard1 [junit4] 2> 6 /solr/collections/testErrorChecks-collection/state.json [junit4] 2> 4 /solr/collections/collection_two/state.json [junit4] 2> 4 /solr/collections/collection_one/terms/shard1 [junit4] 2> 4 /solr/collections/collection_new/state.json [junit4] 2> 4 /solr/collections/collection_new/terms/shard1 [junit4] 2> 4 /solr/collections/collection2/terms/shard1 [junit4] 2> 4 /solr/collections/collection_old/terms/shard2 [junit4] 2> 4 /solr/collections/collection_old/terms/shard1 [junit4] 2> 4 /solr/collections/collection1/terms/shard1 [junit4] 2> 3 /solr/collections/collection1/state.json [junit4] 2> 3 /solr/collections/collection_two/terms/shard1 [junit4] 2> 3 /solr/collections/collection_old/state.json [junit4] 2> 3 /solr/collections/testErrorChecks-collection/terms/shard1 [junit4] 2> 3 /solr/collections/testErrorChecks-collection/terms/shard2 [junit4] 2> 3 /solr/collections/collection1/terms/shard2 [junit4] 2> 2 /solr/collections/collection_old/collectionprops.json [junit4] 2> 2 /solr/collections/collection_one/terms/shard2 [junit4] 2> 2 /solr/security.json [junit4] 2> 2 /solr/collections/collection_one/collectionprops.json [junit4] 2> 2 /solr/collections/collection1/collectionprops.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 475 /solr/aliases.json [junit4] 2> 96 /solr/collections/collection1meta/state.json [junit4] 2> 63 /solr/collections/collection2meta/state.json [junit4] 2> 27 /solr/collections/collection_one/state.json [junit4] 2> 27 /solr/collections/collection_old/state.json [junit4] 2> 26 /solr/collections/collection1/state.json [junit4] 2> 22 /solr/clusterprops.json [junit4] 2> 22 /solr/clusterstate.json [junit4] 2> 20 /solr/collections/testErrorChecks-collection/state.json [junit4] 2> 15 /solr/collections/collection_new/state.json [junit4] 2> 15 /solr/collections/collection_two/state.json [junit4] 2> 9 /solr/collections/collection2/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 299 /solr/collections [junit4] 2> 263 /solr/overseer/queue [junit4] 2> 233 /solr/overseer/collection-queue-work [junit4] 2> 27 /solr/live_nodes [junit4] 2> [junit4] 2> 144386 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42303 [junit4] 2> 144386 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42303 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001 [junit4] 2> Aug 06, 2019 1:28:30 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {a_t=PostingsFormat(name=Asserting), _root_=PostingsFormat(name=Asserting), id=FSTOrd50}, docValues:{}, maxPointsInLeafNode=1536, maxMBSortInHeap=7.960667339327415, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3d1fd), locale=de, timezone=Asia/Katmandu [junit4] 2> NOTE: Linux 5.0.0-23-generic i386/Oracle Corporation 1.8.0_201 (32-bit)/cpus=8,threads=1,free=131156640,total=268435456 [junit4] 2> NOTE: All tests run in this JVM: [DistributedFacetPivotLargeTest, LoggingHandlerTest, HdfsNNFailoverTest, RequestHandlersTest, TestIndexingPerformance, DirectSolrConnectionTest, TestDocTermOrdsUninvertLimit, TestAuthorizationFramework, TestSQLHandlerNonCloud, StatelessScriptUpdateProcessorFactoryTest, TestManagedSynonymFilterFactory, TestLegacyNumericUtils, AliasIntegrationTest] [junit4] Completed [33/867 (1!)] on J1 in 61.51s, 9 tests, 1 failure <<< FAILURES! [...truncated 50734 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.2-Linux/525/consoleText [repro] Revision: 7b0c7cada62a361aa9b00ecedb804e890f1724d4 [repro] Ant options: "-Dargs=-server -XX:+UseG1GC" [repro] Repro line: ant test -Dtestcase=AliasIntegrationTest -Dtests.method=testClusterStateProviderAPI -Dtests.seed=921035B7799D9533 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=Asia/Katmandu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr/core [repro] AliasIntegrationTest [repro] ant compile-test [...truncated 3577 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.AliasIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseG1GC" -Dtests.seed=921035B7799D9533 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=Asia/Katmandu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [...truncated 110 lines...] [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.AliasIntegrationTest [repro] Exiting with code 0 [...truncated 43 lines...] ------=_Part_18_1698188135.1565059079216 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_18_1698188135.1565059079216--