You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/03/12 01:08:24 UTC
[JENKINS-EA] Lucene-Solr-master-Linux
(64bit/jdk-12-ea+shipilev-fastdebug) - Build # 23769 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/23769/
Java: 64bit/jdk-12-ea+shipilev-fastdebug -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.BasicDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=25438, name=testExecutor-7916-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=25438, name=testExecutor-7916-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35043/el/zh: ADDREPLICA failed to create replica
at __randomizedtesting.SeedInfo.seed([79C20C6F9508D8D3]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
at org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCollectionInOneInstance$1(BasicDistributedZkTest.java:657)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:835)
Build Log:
[...truncated 14308 lines...]
[junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4] 2> 2152796 INFO (SUITE-BasicDistributedZkTest-seed#[79C20C6F9508D8D3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/init-core-data-001
[junit4] 2> 2152798 WARN (SUITE-BasicDistributedZkTest-seed#[79C20C6F9508D8D3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1283 numCloses=1283
[junit4] 2> 2152798 INFO (SUITE-BasicDistributedZkTest-seed#[79C20C6F9508D8D3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2152799 INFO (SUITE-BasicDistributedZkTest-seed#[79C20C6F9508D8D3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-5776")
[junit4] 2> 2152800 INFO (SUITE-BasicDistributedZkTest-seed#[79C20C6F9508D8D3]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /el/zh
[junit4] 2> 2152825 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2152828 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2152828 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2152928 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer start zk server on port:33553
[junit4] 2> 2152928 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33553
[junit4] 2> 2152928 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33553
[junit4] 2> 2152972 INFO (zkConnectionManagerCallback-9340-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2152986 INFO (zkConnectionManagerCallback-9342-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2152987 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2152989 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2152991 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2152992 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2152994 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2152995 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2152996 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2152997 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2152998 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2152999 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2153000 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2153002 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 2153210 WARN (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2153211 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2153211 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2153211 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229
[junit4] 2> 2153212 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2153212 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2153212 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2153213 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@659d7caa{/el/zh,null,AVAILABLE}
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16e6e8da{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:41517}
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.s.Server Started @2153461ms
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/el/zh, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/tempDir-001/control/data, hostPort=41517, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/control-001/cores, replicaType=NRT}
[junit4] 2> 2153214 ERROR (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2153214 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-12T00:09:02.633749Z
[junit4] 2> 2153216 INFO (zkConnectionManagerCallback-9344-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2153217 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2153217 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/control-001/solr.xml
[junit4] 2> 2153221 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2153221 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2153222 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2153253 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2153254 WARN (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@46faf574[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2153258 WARN (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@35332b4f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2153259 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33553/solr
[junit4] 2> 2153260 INFO (zkConnectionManagerCallback-9350-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2153264 INFO (zkConnectionManagerCallback-9352-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2153352 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41517_el%2Fzh
[junit4] 2> 2153353 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.Overseer Overseer (id=72183680039387140-127.0.0.1:41517_el%2Fzh-n_0000000000) starting
[junit4] 2> 2153359 INFO (zkConnectionManagerCallback-9359-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2153361 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33553/solr ready
[junit4] 2> 2153362 INFO (OverseerStateUpdate-72183680039387140-127.0.0.1:41517_el%2Fzh-n_0000000000) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41517_el%2Fzh
[junit4] 2> 2153367 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41517_el%2Fzh
[junit4] 2> 2153368 INFO (zkCallback-9351-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2153368 INFO (zkCallback-9358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2153393 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2153433 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2153464 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2153464 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2153467 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/control-001/cores
[junit4] 2> 2153504 INFO (zkConnectionManagerCallback-9365-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2153505 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2153505 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33553/solr ready
[junit4] 2> 2153507 INFO (qtp669305080-24723) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:41517_el%252Fzh&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2153509 INFO (OverseerThreadFactory-7769-thread-1-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 2153614 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2153615 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2154667 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2154724 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 2154854 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2154868 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 2154868 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2154868 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 2154873 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=7.9736328125, floorSegmentMB=1.1806640625, forceMergeDeletesPctAllowed=22.743122029719043, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=33.148130209203096
[junit4] 2> 2154877 WARN (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2154937 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2154937 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2154941 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2154941 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2154943 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1652571719, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2154943 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3596966e[control_collection_shard1_replica_n1] main]
[junit4] 2> 2154944 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2154945 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2154946 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2154946 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627756266116874240
[junit4] 2> 2154950 INFO (searcherExecutor-7774-thread-1-processing-n:127.0.0.1:41517_el%2Fzh x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@3596966e[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2154952 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2154952 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 2154954 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2154954 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2154954 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:41517/el/zh/control_collection_shard1_replica_n1/
[junit4] 2> 2154955 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2154955 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:41517/el/zh/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 2154955 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72183680039387140-core_node2-n_0000000000
[junit4] 2> 2154956 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:41517/el/zh/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 2155057 INFO (zkCallback-9351-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2155058 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2155118 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1503
[junit4] 2> 2155120 INFO (qtp669305080-24723) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2155160 INFO (zkCallback-9351-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2155160 INFO (zkCallback-9351-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2155161 INFO (qtp669305080-24723) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:41517_el%252Fzh&wt=javabin&version=2} status=0 QTime=1653
[junit4] 2> 2155166 INFO (zkConnectionManagerCallback-9371-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2155168 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2155168 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33553/solr ready
[junit4] 2> 2155169 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2155170 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2155176 INFO (OverseerThreadFactory-7769-thread-2-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2155177 INFO (OverseerCollectionConfigSetProcessor-72183680039387140-127.0.0.1:41517_el%2Fzh-n_0000000000) [n:127.0.0.1:41517_el%2Fzh ] 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> 2155379 WARN (OverseerThreadFactory-7769-thread-2-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 2155380 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2155381 INFO (qtp669305080-24722) [n:127.0.0.1:41517_el%2Fzh ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=211
[junit4] 2> 2155382 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 2155594 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-1-001 of type NRT
[junit4] 2> 2155603 WARN (closeThreadPool-9372-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2155604 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2155604 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2155604 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229
[junit4] 2> 2155632 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2155632 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2155632 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f10ce15{/el/zh,null,AVAILABLE}
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1007bb1{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:35043}
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.Server Started @2155887ms
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/el/zh, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/tempDir-001/jetty1, hostPort=35043, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-1-001/cores}
[junit4] 2> 2155640 ERROR (closeThreadPool-9372-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2155640 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-12T00:09:05.059887Z
[junit4] 2> 2155644 INFO (zkConnectionManagerCallback-9374-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2155646 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2155646 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-1-001/solr.xml
[junit4] 2> 2155650 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2155650 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2155651 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2155713 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2155714 WARN (closeThreadPool-9372-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2bc6cd1d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2155717 WARN (closeThreadPool-9372-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7f58008d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2155718 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33553/solr
[junit4] 2> 2155766 INFO (zkConnectionManagerCallback-9380-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2155769 INFO (zkConnectionManagerCallback-9382-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2155773 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2155775 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.c.ZkController Publish node=127.0.0.1:35043_el%2Fzh as DOWN
[junit4] 2> 2155776 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2155776 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35043_el%2Fzh
[junit4] 2> 2155777 INFO (zkCallback-9351-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2155777 INFO (zkCallback-9370-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2155777 INFO (zkCallback-9358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2155777 INFO (zkCallback-9381-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2155791 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-2-001 of type NRT
[junit4] 2> 2155792 WARN (closeThreadPool-9372-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2155793 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2155793 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2155793 INFO (closeThreadPool-9372-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229
[junit4] 2> 2155796 INFO (zkConnectionManagerCallback-9389-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2155797 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2155798 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33553/solr ready
[junit4] 2> 2155806 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2155807 INFO (closeThreadPool-9372-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2155807 INFO (closeThreadPool-9372-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2155807 INFO (closeThreadPool-9372-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6abd6399{/el/zh,null,AVAILABLE}
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5c3e9085{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42335}
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.e.j.s.Server Started @2156055ms
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/el/zh, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/tempDir-001/jetty2, hostPort=42335, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-2-001/cores}
[junit4] 2> 2155808 ERROR (closeThreadPool-9372-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2155808 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-12T00:09:05.227877Z
[junit4] 2> 2155824 INFO (zkConnectionManagerCallback-9392-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2155826 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2155826 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-2-001/solr.xml
[junit4] 2> 2155830 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2155830 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2155831 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2155845 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2155874 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2155875 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2155877 INFO (closeThreadPool-9372-thread-1) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-1-001/cores
[junit4] 2> 2155998 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-3-001 of type NRT
[junit4] 2> 2155999 WARN (closeThreadPool-9372-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2155999 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2155999 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2155999 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229
[junit4] 2> 2156002 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2156002 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2156002 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2334a272{/el/zh,null,AVAILABLE}
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4bd5fced{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:43775}
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.e.j.s.Server Started @2156251ms
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/el/zh, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/tempDir-001/jetty3, hostPort=43775, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-3-001/cores}
[junit4] 2> 2156004 ERROR (closeThreadPool-9372-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2156004 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-12T00:09:05.423816Z
[junit4] 2> 2156015 INFO (zkConnectionManagerCallback-9394-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156016 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2156016 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-3-001/solr.xml
[junit4] 2> 2156019 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2156019 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2156020 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2156114 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2156123 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2156125 WARN (closeThreadPool-9372-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6165e370[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2156129 WARN (closeThreadPool-9372-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@393e5cea[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2156143 WARN (closeThreadPool-9372-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6bf3db0a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2156144 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33553/solr
[junit4] 2> 2156156 WARN (closeThreadPool-9372-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@466b61bb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2156157 INFO (closeThreadPool-9372-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33553/solr
[junit4] 2> 2156162 INFO (zkConnectionManagerCallback-9403-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156163 INFO (zkConnectionManagerCallback-9406-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156173 INFO (TEST-BasicDistributedZkTest.test-seed#[79C20C6F9508D8D3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-4-001 of type NRT
[junit4] 2> 2156176 INFO (zkConnectionManagerCallback-9408-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156176 WARN (closeThreadPool-9372-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2156178 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2156178 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2156178 INFO (closeThreadPool-9372-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229
[junit4] 2> 2156178 INFO (zkConnectionManagerCallback-9410-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156186 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2156187 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2156189 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.c.ZkController Publish node=127.0.0.1:42335_el%2Fzh as DOWN
[junit4] 2> 2156190 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2156190 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42335_el%2Fzh
[junit4] 2> 2156191 INFO (zkCallback-9358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2156191 INFO (zkCallback-9381-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2156191 INFO (zkCallback-9370-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2156191 INFO (zkCallback-9351-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2156192 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:43775_el%2Fzh as DOWN
[junit4] 2> 2156196 INFO (closeThreadPool-9372-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2156196 INFO (closeThreadPool-9372-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2156196 INFO (closeThreadPool-9372-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2156196 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2156196 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43775_el%2Fzh
[junit4] 2> 2156197 INFO (zkCallback-9358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2156198 INFO (zkCallback-9381-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2156198 INFO (zkCallback-9370-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2156198 INFO (zkCallback-9351-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2156263 INFO (closeThreadPool-9372-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d67b32e{/el/zh,null,AVAILABLE}
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@73c4e5cf{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40857}
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.e.j.s.Server Started @2156511ms
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/el/zh, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/tempDir-001/jetty4, hostPort=40857, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-4-001/cores}
[junit4] 2> 2156264 ERROR (closeThreadPool-9372-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2156264 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-12T00:09:05.683635Z
[junit4] 2> 2156270 INFO (zkCallback-9388-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2156270 INFO (zkCallback-9407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2156271 INFO (zkCallback-9409-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2156271 INFO (zkConnectionManagerCallback-9416-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156272 INFO (zkConnectionManagerCallback-9421-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156274 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2156274 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-4-001/solr.xml
[junit4] 2> 2156278 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2156278 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2156279 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2156280 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2156281 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33553/solr ready
[junit4] 2> 2156281 INFO (zkConnectionManagerCallback-9426-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156287 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2156288 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2156296 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33553/solr ready
[junit4] 2> 2156303 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2156329 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156343 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156369 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156370 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156373 INFO (closeThreadPool-9372-thread-2) [n:127.0.0.1:42335_el%2Fzh ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-2-001/cores
[junit4] 2> 2156381 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156381 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156389 INFO (closeThreadPool-9372-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-3-001/cores
[junit4] 2> 2156487 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2156489 WARN (closeThreadPool-9372-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5082d93f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2156496 WARN (closeThreadPool-9372-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@73fba2c6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2156497 INFO (closeThreadPool-9372-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33553/solr
[junit4] 2> 2156504 INFO (zkConnectionManagerCallback-9434-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156531 INFO (zkConnectionManagerCallback-9436-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156535 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2156538 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.c.ZkController Publish node=127.0.0.1:40857_el%2Fzh as DOWN
[junit4] 2> 2156539 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2156539 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40857_el%2Fzh
[junit4] 2> 2156541 INFO (zkCallback-9407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156541 INFO (zkCallback-9370-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156541 INFO (zkCallback-9358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156541 INFO (zkCallback-9409-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156541 INFO (zkCallback-9381-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156541 INFO (zkCallback-9388-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156543 INFO (zkCallback-9351-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156551 INFO (zkCallback-9435-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156560 INFO (zkCallback-9425-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156560 INFO (zkCallback-9420-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2156576 INFO (zkConnectionManagerCallback-9443-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2156577 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 2156578 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33553/solr ready
[junit4] 2> 2156593 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2156626 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156650 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156650 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2156652 INFO (closeThreadPool-9372-thread-3) [n:127.0.0.1:40857_el%2Fzh ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-4-001/cores
[junit4] 2> 2156802 INFO (qtp299344022-24783) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:43775_el%252Fzh&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2156802 INFO (qtp299344022-24784) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:35043_el%252Fzh&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2156808 INFO (qtp299344022-24785) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:42335_el%252Fzh&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2156808 INFO (OverseerThreadFactory-7769-thread-3-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:35043_el%2Fzh for creating new replica of shard shard2 for collection collection1
[junit4] 2> 2156808 INFO (OverseerCollectionConfigSetProcessor-72183680039387140-127.0.0.1:41517_el%2Fzh-n_0000000000) [n:127.0.0.1:41517_el%2Fzh ] 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> 2156809 INFO (OverseerThreadFactory-7769-thread-3-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2156816 INFO (qtp299344022-24919) [n:127.0.0.1:35043_el%2Fzh ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:40857_el%252Fzh&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2156817 INFO (OverseerThreadFactory-7769-thread-4-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:42335_el%2Fzh for creating new replica of shard shard1 for collection collection1
[junit4] 2> 2156818 INFO (OverseerThreadFactory-7769-thread-4-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2156818 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh x:collection1_shard2_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2156824 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh x:collection1_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n2&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2157931 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2157932 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2157948 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema [collection1_shard2_replica_n1] Schema name=test
[junit4] 2> 2157949 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.s.IndexSchema [collection1_shard1_replica_n2] Schema name=test
[junit4] 2> 2158212 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2158212 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2158227 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 2158228 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 2158228 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n2' (registry 'solr.core.collection1.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2158228 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n1' (registry 'solr.core.collection1.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d014314
[junit4] 2> 2158228 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SolrCore [[collection1_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-2-001/cores/collection1_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-2-001/cores/collection1_shard1_replica_n2/data/]
[junit4] 2> 2158228 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [[collection1_shard2_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-1-001/cores/collection1_shard2_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_79C20C6F9508D8D3-001/shard-1-001/cores/collection1_shard2_replica_n1/data/]
[junit4] 2> 2158232 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=7.9736328125, floorSegmentMB=1.1806640625, forceMergeDeletesPctAllowed=22.743122029719043, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=33.148130209203096
[junit4] 2> 2158234 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=7.9736328125, floorSegmentMB=1.1806640625, forceMergeDeletesPctAllowed=22.743122029719043, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=33.148130209203096
[junit4] 2> 2158234 WARN (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2158235 WARN (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2158349 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2158349 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2158352 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2158352 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2158353 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2158353 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2158355 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1652571719, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2158355 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2737c0db[collection1_shard2_replica_n1] main]
[junit4] 2> 2158355 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2158355 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2158356 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2158356 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2158357 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2158357 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627756269693566976
[junit4] 2> 2158357 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1652571719, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2158357 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c71c78d[collection1_shard1_replica_n2] main]
[junit4] 2> 2158358 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2158359 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2158359 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2158360 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627756269696712704
[junit4] 2> 2158360 INFO (searcherExecutor-7816-thread-1-processing-n:127.0.0.1:35043_el%2Fzh x:collection1_shard2_replica_n1 c:collection1 s:shard2) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@2737c0db[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2158362 INFO (searcherExecutor-7815-thread-1-processing-n:127.0.0.1:42335_el%2Fzh x:collection1_shard1_replica_n2 c:collection1 s:shard1) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SolrCore [collection1_shard1_replica_n2] Registered new searcher Searcher@3c71c78d[collection1_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2158366 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 2158366 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 2158366 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 2158366 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 2158367 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2158367 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2158367 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35043/el/zh/collection1_shard2_replica_n1/
[junit4] 2> 2158367 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2158367 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:35043/el/zh/collection1_shard2_replica_n1/ has no replicas
[junit4] 2> 2158367 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72183680039387146-core_node3-n_0000000000
[junit4] 2> 2158367 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2158367 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2158367 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42335/el/zh/collection1_shard1_replica_n2/
[junit4] 2> 2158367 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2158367 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:42335/el/zh/collection1_shard1_replica_n2/ has no replicas
[junit4] 2> 2158367 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72183680039387152-core_node4-n_0000000000
[junit4] 2> 2158368 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42335/el/zh/collection1_shard1_replica_n2/ shard1
[junit4] 2> 2158368 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:35043/el/zh/collection1_shard2_replica_n1/ shard2
[junit4] 2> 2158470 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2158470 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2158498 INFO (qtp1592125919-24814) [n:127.0.0.1:42335_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n2&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1674
[junit4] 2> 2158498 INFO (qtp299344022-24918) [n:127.0.0.1:35043_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1680
[junit4] 2> 2158500 INFO (qtp299344022-24784) [n:127.0.0.1:35043_el%2Fzh c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:35043_el%252Fzh&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1698
[junit4] 2> 2158500 INFO (qtp299344022-24785) [n:127.0.0.1:35043_el%2Fzh c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:42335_el%252Fzh&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=1692
[junit4] 2> 2158819 INFO (OverseerThreadFactory-7769-thread-3-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:40857_el%2Fzh for creating new replica of shard shard1 for collection collection1
[junit4] 2> 2158819 INFO (OverseerThreadFactory-7769-thread-5-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:43775_el%2Fzh for creating new replica of shard shard2 for collection collection1
[junit4] 2> 2158819 INFO (OverseerCollectionConfigSetProcessor-72183680039387140-127.0.0.1:41517_el%2Fzh-n_0000000000) [n:127.0.0.1:41517_el%2Fzh ] 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> 2158819 INFO (OverseerCollectionConfigSetProcessor-72183680039387140-127.0.0.1:41517_el%2Fzh-n_0000000000) [n:127.0.0.1:41517_el%2Fzh ] 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> 2158820 INFO (OverseerThreadFactory-7769-thread-3-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2158820 INFO (OverseerThreadFactory-7769-thread-5-processing-n:127.0.0.1:41517_el%2Fzh) [n:127.0.0.1:41517_el%2Fzh c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2158822 INFO (qtp1653157363-24863) [n:127.0.0.1:40857_el%2Fzh x:collection1_shard1_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n5&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2158822 INFO (qtp684424913-24830) [n:127.0.0.1:43775_el%2Fzh x:collection1_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n6&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2159871 INFO (qtp1653157363-24863) [n:127.0.0.1:40857_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2159874 INFO (qtp684424913-24830) [n:127.0.0.1:43775_el%2Fzh c:collection1 s:shard2 x:collection1_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2159885 INFO (qtp1653157363-24863) [n:127.0.0.1:40857_el%2Fzh c:collection1 s:shard1 x:collection1_shard1_replica_n5] o.a.s.s.IndexSchema [collection1_shard1_replica_n5] Schema name=test
[junit4] 2> 215996
[...truncated too long message...]
ess(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
[junit4] > at org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCollectionInOneInstance$1(BasicDistributedZkTest.java:657)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)Throwable #3: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=25439, name=testExecutor-7916-thread-2, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
[junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35043/el/zh: ADDREPLICA failed to create replica
[junit4] > at __randomizedtesting.SeedInfo.seed([79C20C6F9508D8D3]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
[junit4] > at org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCollectionInOneInstance$1(BasicDistributedZkTest.java:657)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> 2584965 INFO (SUITE-BasicDistributedZkTest-seed#[79C20C6F9508D8D3]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {regex_dup_A_s=FSTOrd50, regex_dup_B_s=Lucene50(blocksize=128), SubjectTerms_mfacet=PostingsFormat(name=Asserting), multiDefault=Lucene50(blocksize=128), _root_=PostingsFormat(name=Asserting), genre_s=BlockTreeOrds(blocksize=128), author_t=PostingsFormat(name=Asserting), series_t=PostingsFormat(name=Asserting), rnd_b=BlockTreeOrds(blocksize=128), oddField_s=BlockTreeOrds(blocksize=128), a_t=Lucene50(blocksize=128), cat=Lucene50(blocksize=128), foo_b=FSTOrd50, name=BlockTreeOrds(blocksize=128), inStock=FSTOrd50, id=BlockTreeOrds(blocksize=128), text=FSTOrd50}, docValues:{regex_dup_A_s=DocValuesFormat(name=Lucene80), other_tl1=DocValuesFormat(name=Direct), regex_dup_B_s=DocValuesFormat(name=Lucene80), range_facet_l_dv=DocValuesFormat(name=Asserting), SubjectTerms_mfacet=DocValuesFormat(name=Direct), n_l1=DocValuesFormat(name=Lucene80), multiDefault=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Direct), genre_s=DocValuesFormat(name=Asserting), n_td1=DocValuesFormat(name=Direct), n_d1=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Lucene80), n_f1=DocValuesFormat(name=Direct), series_t=DocValuesFormat(name=Direct), n_tl1=DocValuesFormat(name=Direct), oddField_s=DocValuesFormat(name=Asserting), n_tf1=DocValuesFormat(name=Lucene80), price=DocValuesFormat(name=Lucene80), a_t=DocValuesFormat(name=Lucene80), cat=DocValuesFormat(name=Lucene80), sequence_i=DocValuesFormat(name=Asserting), inStock=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Asserting), text=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene80), foo_i=DocValuesFormat(name=Direct), val_i=DocValuesFormat(name=Asserting), _root_=DocValuesFormat(name=Direct), n_dt1=DocValuesFormat(name=Direct), author_t=DocValuesFormat(name=Direct), a_i1=DocValuesFormat(name=Lucene80), n_ti1=DocValuesFormat(name=Asserting), rnd_b=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Direct), n_tdt1=DocValuesFormat(name=Direct), foo_b=DocValuesFormat(name=Lucene80), name=DocValuesFormat(name=Asserting), id_i1=DocValuesFormat(name=Direct), foo_d=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Lucene80), foo_f=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1179, maxMBSortInHeap=5.620591983776029, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@157978bf), locale=en-WS, timezone=Asia/Choibalsan
[junit4] 2> NOTE: Linux 4.18.0-15-generic amd64/Aleksey Shipilev 12-testing (64-bit)/cpus=8,threads=1,free=207169488,total=477626368
[junit4] 2> NOTE: All tests run in this JVM: [TestCloudRecovery, TestScoreJoinQPScore, TimeRoutedAliasUpdateProcessorTest, BlobRepositoryCloudTest, TestExceedMaxTermLength, MoveReplicaTest, TestPointFields, SolrMetricManagerTest, NodeMutatorTest, TestCollapseQParserPlugin, AddSchemaFieldsUpdateProcessorFactoryTest, HdfsChaosMonkeySafeLeaderTest, CollectionStateFormat2Test, DocValuesNotIndexedTest, TestCollationFieldDocValues, SortSpecParsingTest, OverseerCollectionConfigSetProcessorTest, ComputePlanActionTest, TestFoldingMultitermQuery, DistanceUnitsTest, TestNumericRangeQuery64, TestObjectReleaseTracker, TestAuthorizationFramework, CollectionsAPIDistributedZkTest, TestPivotHelperCode, TestConfigSetsAPIZkFailure, DistributedFacetPivotLongTailTest, TestShardHandlerFactory, OutputWriterTest, PluginInfoTest, TestDynamicLoading, HdfsThreadLeakTest, TestDocumentBuilder, TestLocalFSCloudBackupRestore, ScheduledTriggerTest, TestCloudManagedSchema, TestInPlaceUpdatesRequiredField, DocExpirationUpdateProcessorFactoryTest, TestSerializedLuceneMatchVersion, TestJavabinTupleStreamParser, RequestHandlersTest, SolrGangliaReporterTest, TestUnifiedSolrHighlighter, TestSolrConfigHandlerCloud, TestDistributedGrouping, HighlighterMaxOffsetTest, HdfsCollectionsAPIDistributedZkTest, TestMacroExpander, TestDocBasedVersionConstraints, CurrencyRangeFacetCloudTest, TriggerEventQueueTest, StatsComponentTest, BlockCacheTest, CreateRoutedAliasTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestFieldCacheWithThreads, TestLMDirichletSimilarityFactory, TestBlendedInfixSuggestions, TestReversedWildcardFilterFactory, TestSolrCloudWithHadoopAuthPlugin, IgnoreLargeDocumentProcessorFactoryTest, TestDistribDocBasedVersion, HdfsWriteToMultipleCollectionsTest, TestStandardQParsers, TestSolrXml, RestoreTriggerStateTest, DistanceFunctionTest, TestDistribIDF, SolrJmxReporterTest, TestRestoreCore, TestJsonFacetRefinement, MetricTriggerTest, SparseHLLTest, TestCloudConsistency, TestDistributedStatsComponentCardinality, SoftAutoCommitTest, TestZkChroot, TestCharFilters, SolrShardReporterTest, DistributedIntervalFacetingTest, UUIDFieldTest, XsltUpdateRequestHandlerTest, TestLegacyTerms, PeerSyncReplicationTest, ShardRoutingTest, CustomTermsComponentTest, TestRebalanceLeaders, CopyFieldTest, TestUtils, TestLuceneIndexBackCompat, CdcrVersionReplicationTest, TestCloudNestedDocsSort, TestInfoStreamLogging, ClassificationUpdateProcessorFactoryTest, TestBulkSchemaAPI, TestSolrDeletionPolicy1, TestSha256AuthenticationProvider, TestFaceting, TestLRUCache, TestLRUStatsCache, TestFieldSortValues, TestComponentsName, TestJoin, TestManagedSchemaAPI, ReplicationFactorTest, DistributedSuggestComponentTest, AssignBackwardCompatibilityTest, TestSimpleTrackingShardHandler, SolrCoreCheckLockOnStartupTest, TestFuzzyAnalyzedSuggestions, CoreAdminCreateDiscoverTest, XmlInterpolationTest, V2ApiIntegrationTest, TestSolrCLIRunExample, NotRequiredUniqueKeyTest, TestPullReplicaErrorHandling, ActionThrottleTest, TestNumericTerms64, TestBadConfig, TestSimNodeLostTrigger, SmileWriterTest, SimpleFacetsTest, MetricsHandlerTest, SchemaVersionSpecificBehaviorTest, DirectSolrSpellCheckerTest, ConvertedLegacyTest, TestSchemaResource, TestSolr4Spatial2, ZkStateReaderTest, TestRangeQuery, ZkShardTermsTest, TestSortableTextField, TestCloudPhrasesIdentificationComponent, HdfsBasicDistributedZk2Test, TestLeaderElectionZkExpiry, DocValuesTest, AnalysisAfterCoreReloadTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestGroupingSearch, TestRandomDVFaceting, BasicDistributedZkTest]
[junit4] Completed [469/844 (1!)] on J2 in 432.67s, 1 test, 1 error <<< FAILURES!
[...truncated 43791 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/23769/consoleText
[repro] Revision: 28c8171fe6c599c2dd096503de2a60d486c1d40d
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=BasicDistributedZkTest -Dtests.method=test -Dtests.seed=79C20C6F9508D8D3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-WS -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] BasicDistributedZkTest
[repro] ant compile-test
[...truncated 3570 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.BasicDistributedZkTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=79C20C6F9508D8D3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-WS -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 94 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.BasicDistributedZkTest
[repro] Exiting with code 0
[...truncated 43 lines...]