You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/06/06 18:44:29 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 1097 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1097/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.servlet.HttpSolrCallGetCoreTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.servlet.HttpSolrCallGetCoreTest: 1) Thread[id=13043, name=SessionTracker, state=TIMED_WAITING, group=TGRP-HttpSolrCallGetCoreTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.servlet.HttpSolrCallGetCoreTest:
1) Thread[id=13043, name=SessionTracker, state=TIMED_WAITING, group=TGRP-HttpSolrCallGetCoreTest]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
at __randomizedtesting.SeedInfo.seed([65E1E6E9CAF685CE]:0)
Build Log:
[...truncated 14372 lines...]
[junit4] Suite: org.apache.solr.servlet.HttpSolrCallGetCoreTest
[junit4] 2> 905850 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 905851 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/data-dir-95-001
[junit4] 2> 905851 WARN (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=53 numCloses=53
[junit4] 2> 905851 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 905859 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 905859 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 905860 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001
[junit4] 2> 905860 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 905860 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 905860 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 905970 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 57773
[junit4] 2> 905970 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:57773
[junit4] 2> 905970 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57773
[junit4] 2> 905970 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57773
[junit4] 2> 905973 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 905979 INFO (zkConnectionManagerCallback-8641-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 905979 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 905991 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 905995 INFO (zkConnectionManagerCallback-8643-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 905995 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 905996 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 906001 INFO (zkConnectionManagerCallback-8645-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 906001 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 906107 WARN (jetty-launcher-8646-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 906108 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 906108 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 906108 INFO (jetty-launcher-8646-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 906109 INFO (jetty-launcher-8646-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a9cc207{/solr,null,AVAILABLE}
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@458ebf59{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57778}
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.e.j.s.Server Started @906323ms
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57778}
[junit4] 2> 906110 ERROR (jetty-launcher-8646-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 906110 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-06T17:55:12.444Z
[junit4] 2> 906114 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 906120 INFO (zkConnectionManagerCallback-8648-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 906120 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 906121 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 906367 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 906368 WARN (jetty-launcher-8646-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@733c8515[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 906368 WARN (jetty-launcher-8646-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@733c8515[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 906375 WARN (jetty-launcher-8646-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@671a052[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 906375 WARN (jetty-launcher-8646-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@671a052[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 906375 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57773/solr
[junit4] 2> 906377 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 906384 INFO (zkConnectionManagerCallback-8659-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 906384 INFO (jetty-launcher-8646-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 906491 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 906494 INFO (zkConnectionManagerCallback-8661-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 906494 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 906820 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57778_solr
[junit4] 2> 906822 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.Overseer Overseer (id=72059195152334853-127.0.0.1:57778_solr-n_0000000000) starting
[junit4] 2> 906840 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57778_solr
[junit4] 2> 906841 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57778_solr
[junit4] 2> 906843 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 906850 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 906851 WARN (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 906903 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 907024 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907030 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907031 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907032 INFO (jetty-launcher-8646-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1
[junit4] 2> 907082 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 907082 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 907086 INFO (zkConnectionManagerCallback-8678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 907086 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 907090 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 907094 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57773/solr ready
[junit4] 2> 907107 INFO (qtp1263093824-13073) [n:127.0.0.1:57778_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=config&maxShardsPerNode=6&name=collection1&nrtReplicas=2&action=CREATE&numShards=3&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 907120 INFO (OverseerThreadFactory-8668-thread-1-processing-n:127.0.0.1:57778_solr) [n:127.0.0.1:57778_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 907237 WARN (OverseerThreadFactory-8668-thread-1-processing-n:127.0.0.1:57778_solr) [n:127.0.0.1:57778_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 2 on collection collection1 is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 907243 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 907244 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 907247 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 907274 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57778/solr",
[junit4] 2> "node_name":"127.0.0.1:57778_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 907287 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57778/solr",
[junit4] 2> "node_name":"127.0.0.1:57778_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 907304 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57778/solr",
[junit4] 2> "node_name":"127.0.0.1:57778_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 907312 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57778/solr",
[junit4] 2> "node_name":"127.0.0.1:57778_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 907320 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"collection1_shard3_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57778/solr",
[junit4] 2> "node_name":"127.0.0.1:57778_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 907327 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"collection1_shard3_replica_n10",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57778/solr",
[junit4] 2> "node_name":"127.0.0.1:57778_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 907540 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 907544 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr x:collection1_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n2&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 907544 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr x:collection1_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n4&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 907545 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr x:collection1_shard3_replica_n8 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node11&collection.configName=config&newCollection=true&name=collection1_shard3_replica_n8&action=CREATE&numShards=3&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 907545 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr x:collection1_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node9&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n6&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 907550 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr x:collection1_shard3_replica_n10 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node12&collection.configName=config&newCollection=true&name=collection1_shard3_replica_n10&action=CREATE&numShards=3&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 907586 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 907586 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 907615 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 907616 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 907618 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 907618 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 907639 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 907639 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset config, trusted=true
[junit4] 2> 907643 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 907643 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 907643 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n4' using configuration from configset config, trusted=true
[junit4] 2> 907644 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907644 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard1_replica_n1/data/]
[junit4] 2> 907652 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 907652 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 907652 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.core.collection1.shard2.replica_n4' (registry 'solr.core.collection1.shard2.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907653 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard2_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard2_replica_n4/data/]
[junit4] 2> 907653 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 907653 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard3_replica_n10' using configuration from configset config, trusted=true
[junit4] 2> 907654 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 907654 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard3_replica_n8' using configuration from configset config, trusted=true
[junit4] 2> 907654 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.core.collection1.shard3.replica_n10' (registry 'solr.core.collection1.shard3.replica_n10') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907654 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.core.collection1.shard3.replica_n8' (registry 'solr.core.collection1.shard3.replica_n8') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907654 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.SolrCore [[collection1_shard3_replica_n10] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard3_replica_n10], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard3_replica_n10/data/]
[junit4] 2> 907654 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.c.SolrCore [[collection1_shard3_replica_n8] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard3_replica_n8], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard3_replica_n8/data/]
[junit4] 2> 907657 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 907658 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 907660 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 907660 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n6' using configuration from configset config, trusted=true
[junit4] 2> 907660 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.core.collection1.shard2.replica_n6' (registry 'solr.core.collection1.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907661 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n6] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard2_replica_n6], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard2_replica_n6/data/]
[junit4] 2> 907666 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 907667 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 907667 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n2' using configuration from configset config, trusted=true
[junit4] 2> 907668 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57778.solr.core.collection1.shard1.replica_n2' (registry 'solr.core.collection1.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7369225b
[junit4] 2> 907668 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard1_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_65E1E6E9CAF685CE-001/tempDir-001/node1/collection1_shard1_replica_n2/data/]
[junit4] 2> 908093 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 908093 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 908118 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 908118 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 908129 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 908129 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 908142 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 908142 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 908156 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 908156 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 908160 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 908162 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 908625 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 908627 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 908627 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 908627 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 908627 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 908628 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668773172962394112
[junit4] 2> 908631 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 908631 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668773172965539840
[junit4] 2> 908644 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 908645 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 908648 INFO (searcherExecutor-8680-thread-1-processing-n:127.0.0.1:57778_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 908649 INFO (searcherExecutor-8688-thread-1-processing-n:127.0.0.1:57778_solr x:collection1_shard2_replica_n6 c:collection1 s:shard2 r:core_node9) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.SolrCore [collection1_shard2_replica_n6] Registered new searcher autowarm time: 0 ms
[junit4] 2> 908649 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 908651 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 908651 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 908652 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 908652 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 908653 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 908653 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 908653 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 908653 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 908653 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 908653 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 908653 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 908654 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 908654 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668773172989657088
[junit4] 2> 908659 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 908659 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 908660 INFO (searcherExecutor-8682-thread-1-processing-n:127.0.0.1:57778_solr x:collection1_shard2_replica_n4 c:collection1 s:shard2 r:core_node7) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.c.SolrCore [collection1_shard2_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 908662 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 908662 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node9=0}, version=0}
[junit4] 2> 908662 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 908664 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 908664 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 908664 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 908665 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 908665 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668773173001191424
[junit4] 2> 908666 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 908667 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 908667 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668773173003288576
[junit4] 2> 908667 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node7=0, core_node9=0}, version=1}
[junit4] 2> 908667 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 908667 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 908667 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 908667 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668773173003288576
[junit4] 2> 908669 INFO (searcherExecutor-8684-thread-1-processing-n:127.0.0.1:57778_solr x:collection1_shard3_replica_n10 c:collection1 s:shard3 r:core_node12) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.SolrCore [collection1_shard3_replica_n10] Registered new searcher autowarm time: 0 ms
[junit4] 2> 908670 INFO (searcherExecutor-8690-thread-1-processing-n:127.0.0.1:57778_solr x:collection1_shard1_replica_n2 c:collection1 s:shard1 r:core_node5) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrCore [collection1_shard1_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 908673 INFO (searcherExecutor-8686-thread-1-processing-n:127.0.0.1:57778_solr x:collection1_shard3_replica_n8 c:collection1 s:shard3 r:core_node11) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.c.SolrCore [collection1_shard3_replica_n8] Registered new searcher autowarm time: 0 ms
[junit4] 2> 908680 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoute in=9995ms
[junit4] 2> 908683 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard3 to Terms{values={core_node12=0}, version=0}
[junit4] 2> 908683 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3
[junit4] 2> 908685 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard3 to Terms{values={core_node12=0, core_node11=0}, version=1}
[junit4] 2> 908685 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3
[junit4] 2> 908687 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 908687 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1}
[junit4] 2> 908687 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 908687 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 908687 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57778/solr/collection1_shard2_replica_n6/
[junit4] 2> 908690 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n6 url=http://127.0.0.1:57778/solr START replicas=[http://127.0.0.1:57778/solr/collection1_shard2_replica_n4/] nUpdates=100
[junit4] 2> 908690 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n6 url=http://127.0.0.1:57778/solr DONE. We have no versions. sync failed.
[junit4] 2> 908694 INFO (qtp1263093824-13133) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_n4 ] o.a.s.c.S.Request [collection1_shard2_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 908694 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 908694 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 908694 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72059195152334853-core_node9-n_0000000000
[junit4] 2> 908701 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57778/solr/collection1_shard2_replica_n6/ shard2
[junit4] 2> 908702 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 908702 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 908702 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57778/solr/collection1_shard3_replica_n10/
[junit4] 2> 908702 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard3_replica_n10 url=http://127.0.0.1:57778/solr START replicas=[http://127.0.0.1:57778/solr/collection1_shard3_replica_n8/] nUpdates=100
[junit4] 2> 908702 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard3_replica_n10 url=http://127.0.0.1:57778/solr DONE. We have no versions. sync failed.
[junit4] 2> 908707 INFO (qtp1263093824-13133) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node11 x:collection1_shard3_replica_n8 ] o.a.s.c.S.Request [collection1_shard3_replica_n8] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 908707 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 908707 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 908707 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard3/leader after winning as /collections/collection1/leader_elect/shard3/election/72059195152334853-core_node12-n_0000000000
[junit4] 2> 908711 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57778/solr/collection1_shard3_replica_n10/ shard3
[junit4] 2> 908821 INFO (zkCallback-8660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908821 INFO (zkCallback-8660-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908821 INFO (zkCallback-8660-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908822 INFO (zkCallback-8660-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908823 INFO (zkCallback-8660-thread-5) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908829 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr c:collection1 s:shard2 r:core_node9 x:collection1_shard2_replica_n6 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 908830 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n10 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 908846 INFO (qtp1263093824-13116) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node9&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n6&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1301
[junit4] 2> 908848 INFO (qtp1263093824-13117) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node12&collection.configName=config&newCollection=true&name=collection1_shard3_replica_n10&action=CREATE&numShards=3&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1298
[junit4] 2> 908943 INFO (zkCallback-8660-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908943 INFO (zkCallback-8660-thread-5) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908943 INFO (zkCallback-8660-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908944 INFO (zkCallback-8660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908944 INFO (zkCallback-8660-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 908944 INFO (zkCallback-8660-thread-6) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909191 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 909192 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 909192 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57778/solr/collection1_shard1_replica_n1/
[junit4] 2> 909192 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n1 url=http://127.0.0.1:57778/solr START replicas=[http://127.0.0.1:57778/solr/collection1_shard1_replica_n2/] nUpdates=100
[junit4] 2> 909192 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n1 url=http://127.0.0.1:57778/solr DONE. We have no versions. sync failed.
[junit4] 2> 909194 INFO (qtp1263093824-13133) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_n2 ] o.a.s.c.S.Request [collection1_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 909194 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 909194 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 909194 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72059195152334853-core_node3-n_0000000000
[junit4] 2> 909197 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57778/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 909302 INFO (zkCallback-8660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909303 INFO (zkCallback-8660-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909304 INFO (zkCallback-8660-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909305 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 909306 INFO (zkCallback-8660-thread-7) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909306 INFO (zkCallback-8660-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909307 INFO (zkCallback-8660-thread-6) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909310 INFO (qtp1263093824-13074) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1770
[junit4] 2> 909419 INFO (zkCallback-8660-thread-7) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909419 INFO (zkCallback-8660-thread-6) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909419 INFO (zkCallback-8660-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909419 INFO (zkCallback-8660-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909419 INFO (zkCallback-8660-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909420 INFO (zkCallback-8660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909697 INFO (qtp1263093824-13075) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n4&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2153
[junit4] 2> 909711 INFO (qtp1263093824-13072) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n2&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2168
[junit4] 2> 909713 INFO (qtp1263093824-13115) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node11&collection.configName=config&newCollection=true&name=collection1_shard3_replica_n8&action=CREATE&numShards=3&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2168
[junit4] 2> 909716 INFO (qtp1263093824-13073) [n:127.0.0.1:57778_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 909815 INFO (zkCallback-8660-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909815 INFO (zkCallback-8660-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909815 INFO (zkCallback-8660-thread-6) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909815 INFO (zkCallback-8660-thread-7) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909815 INFO (zkCallback-8660-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909815 INFO (zkCallback-8660-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 909818 INFO (qtp1263093824-13073) [n:127.0.0.1:57778_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=config&maxShardsPerNode=6&name=collection1&nrtReplicas=2&action=CREATE&numShards=3&wt=javabin&version=2} status=0 QTime=2711
[junit4] 2> 909819 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout: true timeout (sec):
[junit4] 2> 909822 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 909849 INFO (TEST-HttpSolrCallGetCoreTest.test-seed#[65E1E6E9CAF685CE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 909861 INFO (TEST-HttpSolrCallGetCoreTest.test-seed#[65E1E6E9CAF685CE]) [n:127.0.0.1:57778_solr ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 909978 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1115871706
[junit4] 2> 909978 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57778_solr
[junit4] 2> 909981 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 909981 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:57778_solr as DOWN
[junit4] 2> 910002 INFO (coreCloseExecutor-8719-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@1abde154
[junit4] 2> 910002 INFO (coreCloseExecutor-8719-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.replica_n1 tag=SolrCore@1abde154
[junit4] 2> 910004 INFO (coreCloseExecutor-8719-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a20f307: rootName = solr_57778, domain = solr.core.collection1.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection1.shard1.replica_n1/com.codahale.metrics.MetricRegistry@4ed05b15
[junit4] 2> 910004 INFO (coreCloseExecutor-8719-thread-3) [n:127.0.0.1:57778_solr ] o.a.s.c.SolrCore [collection1_shard2_replica_n4] CLOSING SolrCore org.apache.solr.core.SolrCore@75a35a09
[junit4] 2> 910004 INFO (coreCloseExecutor-8719-thread-4) [n:127.0.0.1:57778_solr ] o.a.s.c.SolrCore [collection1_shard3_replica_n10] CLOSING SolrCore org.apache.solr.core.SolrCore@7153954e
[junit4] 2> 910005 INFO (coreCloseExecutor-8719-thread-5) [n:127.0.0.1:57778_solr ] o.a.s.c.SolrCore [collection1_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@1b4dc046
[junit4] 2> 910009 INFO (coreCloseExecutor-8719-thread-6) [n:127.0.0.1:57778_solr ] o.a.s.c.SolrCore [collection1_shard3_replica_n8] CLOSING SolrCore org.apache.solr.core.SolrCore@51af23f4
[junit4] 2> 910011 INFO (coreCloseExecutor-8719-thread-2) [n:127.0.0.1:57778_solr ] o.a.s.c.SolrCore [collection1_shard2_replica_n6] CLOSING SolrCore org.apache.solr.core.SolrCore@3f0f1779
[junit4] 2> 910015 INFO (coreCloseExecutor-8719-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader tag=SolrCore@1abde154
[junit4] 2> 910015 INFO (coreCloseExecutor-8719-thread-3) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n4 tag=SolrCore@75a35a09
[junit4] 2> 910015 INFO (coreCloseExecutor-8719-thread-3) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67e5df27: rootName = solr_57778, domain = solr.core.collection1.shard2.replica_n4, service url = null, agent id = null] for registry solr.core.collection1.shard2.replica_n4/com.codahale.metrics.MetricRegistry@22a3e059
[junit4] 2> 910015 INFO (coreCloseExecutor-8719-thread-1) [n:127.0.0.1:57778_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 910027 INFO (coreCloseExecutor-8719-thread-3) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader tag=SolrCore@75a35a09
[junit4] 2> 910027 INFO (coreCloseExecutor-8719-thread-4) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard3.replica_n10 tag=SolrCore@7153954e
[junit4] 2> 910027 INFO (coreCloseExecutor-8719-thread-4) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f82c9e9: rootName = solr_57778, domain = solr.core.collection1.shard3.replica_n10, service url = null, agent id = null] for registry solr.core.collection1.shard3.replica_n10/com.codahale.metrics.MetricRegistry@7c8303c7
[junit4] 2> 910027 INFO (coreCloseExecutor-8719-thread-3) [n:127.0.0.1:57778_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 910036 INFO (coreCloseExecutor-8719-thread-4) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard3.leader tag=SolrCore@7153954e
[junit4] 2> 910036 INFO (coreCloseExecutor-8719-thread-5) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.replica_n2 tag=SolrCore@1b4dc046
[junit4] 2> 910036 INFO (coreCloseExecutor-8719-thread-5) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19ad592f: rootName = solr_57778, domain = solr.core.collection1.shard1.replica_n2, service url = null, agent id = null] for registry solr.core.collection1.shard1.replica_n2/com.codahale.metrics.MetricRegistry@15b6197
[junit4] 2> 910036 INFO (coreCloseExecutor-8719-thread-4) [n:127.0.0.1:57778_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 910047 INFO (coreCloseExecutor-8719-thread-5) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader tag=SolrCore@1b4dc046
[junit4] 2> 910047 INFO (coreCloseExecutor-8719-thread-6) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard3.replica_n8 tag=SolrCore@51af23f4
[junit4] 2> 910047 INFO (coreCloseExecutor-8719-thread-6) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2146d265: rootName = solr_57778, domain = solr.core.collection1.shard3.replica_n8, service url = null, agent id = null] for registry solr.core.collection1.shard3.replica_n8/com.codahale.metrics.MetricRegistry@77e40a11
[junit4] 2> 910047 INFO (coreCloseExecutor-8719-thread-5) [n:127.0.0.1:57778_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 910056 INFO (coreCloseExecutor-8719-thread-6) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard3.leader tag=SolrCore@51af23f4
[junit4] 2> 910057 INFO (coreCloseExecutor-8719-thread-2) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n6 tag=SolrCore@3f0f1779
[junit4] 2> 910057 INFO (coreCloseExecutor-8719-thread-2) [n:127.0.0.1:57778_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@65b1bae: rootName = solr_57778, domain = solr.core.collection1.shard2.replica_n6, service url = null, agent id = null] for registry solr.core.collection1.shard2.replica_n6/com.codahale.metrics.MetricRegistry@51660a3c
[junit4] 2> 910057 INFO (coreCloseExecutor-8719-thread-6) [n:127.0.0.1:57778_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 910065 INFO (coreCloseExecutor-8719-thread-2) [n:127.0.0.1:57778_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader tag=SolrCore@3f0f1779
[junit4] 2> 910066 INFO (coreCloseExecutor-8719-thread-2) [n:127.0.0.1:57778_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 910069 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 910069 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7f37749b: rootName = solr_57778, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@766a9b97
[junit4] 2> 910077 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 910077 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@30ddd407: rootName = solr_57778, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@da5c28c
[junit4] 2> 910080 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 910080 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@20ff7ed4: rootName = solr_57778, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5ec941a2
[junit4] 2> 910080 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 910081 INFO (closeThreadPool-8721-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72059195152334853-127.0.0.1:57778_solr-n_0000000000) closing
[junit4] 2> 910082 INFO (OverseerStateUpdate-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [n:127.0.0.1:57778_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57778_solr
[junit4] 2> 910084 INFO (OverseerAutoScalingTriggerThread-72059195152334853-127.0.0.1:57778_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 910092 INFO (closeThreadPool-8721-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72059195152334853-127.0.0.1:57778_solr-n_0000000000) closing
[junit4] 2> 910195 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.a.s.c.Overseer Overseer (id=72059195152334853-127.0.0.1:57778_solr-n_0000000000) closing
[junit4] 2> 910198 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@458ebf59{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 910199 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4a9cc207{/solr,null,UNAVAILABLE}
[junit4] 2> 910199 INFO (jetty-closer-8715-thread-1-processing-n:127.0.0.1:57778_solr) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 910202 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [n:127.0.0.1:57778_solr ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 910417 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/collection1/terms/shard3
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 2 /solr/aliases.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 39 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 27 /solr/overseer/queue
[junit4] 2> 4 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> 910419 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [n:127.0.0.1:57778_solr ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:57773
[junit4] 2> 910419 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [n:127.0.0.1:57778_solr ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57773
[junit4] 2> 910419 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [n:127.0.0.1:57778_solr ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57773
[junit4] 2> 910420 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[65E1E6E9CAF685CE]-worker) [n:127.0.0.1:57778_solr ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Jun 06, 2020 5:55:16 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> Jun 06, 2020 5:55:27 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.servlet.HttpSolrCallGetCoreTest:
[junit4] 2> 1) Thread[id=13043, name=SessionTracker, state=TIMED_WAITING, group=TGRP-HttpSolrCallGetCoreTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
[junit4] 2> Jun 06, 2020 5:55:27 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=13043, name=SessionTracker, state=TIMED_WAITING, group=TGRP-HttpSolrCallGetCoreTest]
[junit4] 2> 920865 ERROR (SessionTracker) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : SessionTracker
[junit4] 2> => java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method) [?:1.8.0_201]
[junit4] 2> at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) ~[zookeeper-3.5.7.jar:3.5.7]
[junit4] 2> Jun 06, 2020 5:55:27 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=844, maxMBSortInHeap=7.90181331276564, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@17f9d035), locale=hr-HR, timezone=Etc/GMT+10
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=1,free=152294688,total=362557440
[junit4] 2> NOTE: All tests run in this JVM: [DistributedIntervalFacetingTest, ReplaceNodeNoTargetTest, TestRetrieveFieldsOptimizer, ForceLeaderWithTlogReplicasTest, TestFaceting, FieldAnalysisRequestHandlerTest, ShowFileRequestHandlerTest, BooleanFieldTest, TestSimClusterStateProvider, VMParamsZkACLAndCredentialsProvidersTest, TestSolrCloudWithSecureImpersonation, TestSimExtremeIndexing, OverriddenZkACLAndCredentialsProvidersTest, NodeLostTriggerIntegrationTest, TestSafeXMLParsing, TestCloudRecovery, CollectionStateFormat2Test, GraphQueryTest, TestBlendedInfixSuggestions, TestJettySolrRunner, TestPostingsSolrHighlighter, TestSubQueryTransformer, UpdateRequestProcessorFactoryTest, CachingDirectoryFactoryTest, TestLeaderElectionWithEmptyReplica, SearchHandlerTest, WrapperMergePolicyFactoryTest, DistribJoinFromCollectionTest, TestManagedSchemaAPI, PeerSyncReplicationTest, TestSolrCoreSnapshots, TestRequestStatusCollectionAPI, TestNumericTerms32, TimeRoutedAliasUpdateProcessorTest, SpatialRPTFieldTypeTest, ExecutePlanActionTest, TestPayloadCheckQParserPlugin, FastVectorHighlighterTest, DistanceFunctionTest, DistributedFacetPivotLongTailTest, TestRestoreCore, TestJavabinTupleStreamParser, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestPerFieldSimilarityWithDefaultOverride, TestSolrTestCaseJ4, TestConfigSetProperties, TestLMDirichletSimilarityFactory, ClassificationUpdateProcessorFactoryTest, TestRestManager, TestStressThreadBackup, TestLegacyNumericUtils, TestPivotHelperCode, ResourceLoaderTest, TestExportTool, DebugComponentTest, TestPackages, TestSolrIndexConfig, SpellCheckComponentTest, SolrInfoBeanTest, QueryParsingTest, TestRecovery, DeleteReplicaTest, ZkShardTermsTest, ScheduledTriggerIntegrationTest, TestWithCollection, HttpSolrCallGetCoreTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpSolrCallGetCoreTest -Dtests.seed=65E1E6E9CAF685CE -Dtests.slow=true -Dtests.locale=hr-HR -Dtests.timezone=Etc/GMT+10 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | HttpSolrCallGetCoreTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.servlet.HttpSolrCallGetCoreTest:
[junit4] > 1) Thread[id=13043, name=SessionTracker, state=TIMED_WAITING, group=TGRP-HttpSolrCallGetCoreTest]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
[junit4] > at __randomizedtesting.SeedInfo.seed([65E1E6E9CAF685CE]:0)
[junit4] Completed [305/910 (1!)] on J0 in 15.28s, 1 test, 1 error <<< FAILURES!
[...truncated 54166 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1097/consoleText
[repro] Revision: d456e0bfcef0a7fedf7598009d3407a1bbe57fcc
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=HttpSolrCallGetCoreTest -Dtests.seed=65E1E6E9CAF685CE -Dtests.slow=true -Dtests.locale=hr-HR -Dtests.timezone=Etc/GMT+10 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] HttpSolrCallGetCoreTest
[repro] ant compile-test
[...truncated 3470 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.HttpSolrCallGetCoreTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=65E1E6E9CAF685CE -Dtests.slow=true -Dtests.locale=hr-HR -Dtests.timezone=Etc/GMT+10 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 76 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.servlet.HttpSolrCallGetCoreTest
[repro] Exiting with code 0
[...truncated 73 lines...]
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-11.0.6) - Build # 1098
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1098/
Java: 64bit/jdk-11.0.6 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.test
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([A322F68EEC9A21F9:2B76C95442664C01]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:104)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:972)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 14063 lines...]
[junit4] Suite: org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
[junit4] 2> 557735 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 557735 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 557735 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RoutingToNodesWithPropertiesTest_A322F68EEC9A21F9-001/data-dir-94-001
[junit4] 2> 557735 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 557736 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 557737 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 557738 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RoutingToNodesWithPropertiesTest_A322F68EEC9A21F9-001/tempDir-001
[junit4] 2> 557738 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 557738 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 557738 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 557847 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.ZkTestServer start zk server on port: 62756
[junit4] 2> 557847 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:62756
[junit4] 2> 557847 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62756
[junit4] 2> 557847 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 62756
[junit4] 2> 557849 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 557854 INFO (zkConnectionManagerCallback-5600-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 557854 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 557860 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 557862 INFO (zkConnectionManagerCallback-5602-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 557862 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 557863 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 557866 INFO (zkConnectionManagerCallback-5604-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 557866 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 557973 WARN (jetty-launcher-5605-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 557973 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 557973 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 557973 INFO (jetty-launcher-5605-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 557973 WARN (jetty-launcher-5605-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 557973 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 557973 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 557973 INFO (jetty-launcher-5605-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5adbd983{/solr,null,AVAILABLE}
[junit4] 2> 557976 INFO (jetty-launcher-5605-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@436a918f{/solr,null,AVAILABLE}
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@24040a29{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62761}
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@333ca659{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62762}
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.e.j.s.Server Started @558048ms
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.e.j.s.Server Started @558048ms
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62762}
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62761}
[junit4] 2> 557977 ERROR (jetty-launcher-5605-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 557977 ERROR (jetty-launcher-5605-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-06T23:10:31.540694Z
[junit4] 2> 557977 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-06T23:10:31.540695Z
[junit4] 2> 557978 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 557978 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 557981 INFO (zkConnectionManagerCallback-5609-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 557981 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 557981 INFO (zkConnectionManagerCallback-5608-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 557981 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 557982 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 557982 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 557984 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 557984 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 557984 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 557984 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 557985 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 557985 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 558164 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 558165 WARN (jetty-launcher-5605-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@70dd5c0f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558165 WARN (jetty-launcher-5605-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@70dd5c0f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558167 WARN (jetty-launcher-5605-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@93686b7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558167 WARN (jetty-launcher-5605-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@93686b7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558168 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62756/solr
[junit4] 2> 558168 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 558171 INFO (zkConnectionManagerCallback-5620-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 558171 INFO (jetty-launcher-5605-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 558304 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 558307 INFO (zkConnectionManagerCallback-5625-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 558307 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 558363 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 558364 WARN (jetty-launcher-5605-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a3fb789[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558364 WARN (jetty-launcher-5605-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a3fb789[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558366 WARN (jetty-launcher-5605-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3945e832[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558366 WARN (jetty-launcher-5605-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3945e832[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558367 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62756/solr
[junit4] 2> 558368 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 558373 INFO (zkConnectionManagerCallback-5635-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 558373 INFO (jetty-launcher-5605-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 558467 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62762_solr
[junit4] 2> 558471 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.c.Overseer Overseer (id=72060435034996742-127.0.0.1:62762_solr-n_0000000000) starting
[junit4] 2> 558478 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 558481 INFO (zkConnectionManagerCallback-5637-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 558482 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 558485 INFO (OverseerStateUpdate-72060435034996742-127.0.0.1:62762_solr-n_0000000000) [n:127.0.0.1:62762_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:62762_solr
[junit4] 2> 558486 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62762_solr
[junit4] 2> 558489 INFO (OverseerStateUpdate-72060435034996742-127.0.0.1:62762_solr-n_0000000000) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 558497 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 558498 WARN (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 558502 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 558513 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:62761_solr as DOWN
[junit4] 2> 558515 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 558515 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62761_solr
[junit4] 2> 558518 INFO (zkCallback-5624-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 558518 INFO (zkCallback-5636-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 558522 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 558523 WARN (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 558527 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 558539 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 558544 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 558552 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 558552 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 558553 INFO (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RoutingToNodesWithPropertiesTest_A322F68EEC9A21F9-001/tempDir-001/node2
[junit4] 2> 558556 WARN (jetty-launcher-5605-thread-2) [n:127.0.0.1:62762_solr ] o.a.s.s.SolrDispatchFilter Error registering JVM metrics
[junit4] 2> => java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.management/java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166)
[junit4] 2> java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage0(Native Method) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage(MemoryPoolImpl.java:264) ~[?:?]
[junit4] 2> at com.codahale.metrics.jvm.MemoryUsageGaugeSet.getMetrics(MemoryUsageGaugeSet.java:96) ~[metrics-jvm-4.1.5.jar:4.1.5]
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.registerAll(SolrMetricManager.java:557) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.setupJvmMetrics(SolrDispatchFilter.java:216) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:186) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:134) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.lambda$initialize$0(ServletHandler.java:751) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at java.util.Spliterators$ArraySpliterator.forEachRemaining(Spliterators.java:948) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.ReferencePipeline$Head.forEach(ReferencePipeline.java:658) ~[?:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:744) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1448) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1507) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1164) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1003) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:459) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:382) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:193) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.retryOnPortBindFailure(JettySolrRunner.java:566) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:504) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:472) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:471) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:300) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 558557 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 558575 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 558575 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 558576 INFO (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RoutingToNodesWithPropertiesTest_A322F68EEC9A21F9-001/tempDir-001/node1
[junit4] 2> 558580 WARN (jetty-launcher-5605-thread-1) [n:127.0.0.1:62761_solr ] o.a.s.s.SolrDispatchFilter Error registering JVM metrics
[junit4] 2> => java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.management/java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166)
[junit4] 2> java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage0(Native Method) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage(MemoryPoolImpl.java:264) ~[?:?]
[junit4] 2> at com.codahale.metrics.jvm.MemoryUsageGaugeSet.getMetrics(MemoryUsageGaugeSet.java:96) ~[metrics-jvm-4.1.5.jar:4.1.5]
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.registerAll(SolrMetricManager.java:557) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.setupJvmMetrics(SolrDispatchFilter.java:216) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:186) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:134) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.lambda$initialize$0(ServletHandler.java:751) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at java.util.Spliterators$ArraySpliterator.forEachRemaining(Spliterators.java:948) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.ReferencePipeline$Head.forEach(ReferencePipeline.java:658) ~[?:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:744) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1448) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1507) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1164) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1003) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:459) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:382) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:193) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.retryOnPortBindFailure(JettySolrRunner.java:566) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:504) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:472) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:471) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:300) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 558582 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 558582 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 558585 INFO (zkConnectionManagerCallback-5660-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 558585 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 558587 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 558590 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62756/solr ready
[junit4] 2> 558595 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 558595 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 558595 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 558595 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 558595 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 558595 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 558595 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 558595 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4666868{/solr,null,AVAILABLE}
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2d6335fe{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62773}
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.Server Started @558671ms
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62773}
[junit4] 2> 558600 ERROR (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 558600 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-06T23:10:32.163748Z
[junit4] 2> 558601 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 558603 INFO (zkConnectionManagerCallback-5662-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 558604 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 558604 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 558607 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 558607 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 558607 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 558894 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 558895 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@35289d65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558895 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@35289d65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558897 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@29f4dee9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558897 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@29f4dee9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 558898 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62756/solr
[junit4] 2> 558899 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 558899 INFO (zkConnectionManagerCallback-5673-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 558899 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 559010 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 559016 INFO (zkConnectionManagerCallback-5675-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 559016 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 559045 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 559074 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:62773_solr as DOWN
[junit4] 2> 559078 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 559078 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62773_solr
[junit4] 2> 559083 INFO (zkCallback-5636-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 559084 INFO (zkCallback-5674-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 559084 INFO (zkCallback-5659-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 559085 INFO (zkCallback-5624-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 559086 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 559087 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 559100 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 559118 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 559126 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 559126 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 559127 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RoutingToNodesWithPropertiesTest_A322F68EEC9A21F9-001/tempDir-001/node3
[junit4] 2> 559131 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62773_solr ] o.a.s.s.SolrDispatchFilter Error registering JVM metrics
[junit4] 2> => java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.management/java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166)
[junit4] 2> java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage0(Native Method) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage(MemoryPoolImpl.java:264) ~[?:?]
[junit4] 2> at com.codahale.metrics.jvm.MemoryUsageGaugeSet.getMetrics(MemoryUsageGaugeSet.java:96) ~[metrics-jvm-4.1.5.jar:4.1.5]
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.registerAll(SolrMetricManager.java:557) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.setupJvmMetrics(SolrDispatchFilter.java:216) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:186) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:134) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.lambda$initialize$0(ServletHandler.java:751) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at java.util.Spliterators$ArraySpliterator.forEachRemaining(Spliterators.java:948) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.ReferencePipeline$Head.forEach(ReferencePipeline.java:658) ~[?:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:744) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1448) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1507) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1164) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1003) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:459) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:382) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:193) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.retryOnPortBindFailure(JettySolrRunner.java:566) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:504) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:472) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:471) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:482) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:78) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:972) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 559133 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 559133 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 559133 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 559133 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 559136 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 559136 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 559136 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 559137 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ef41eab{/solr,null,AVAILABLE}
[junit4] 2> 559139 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3336ad80{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62777}
[junit4] 2> 559139 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.s.Server Started @559211ms
[junit4] 2> 559139 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62777}
[junit4] 2> 559140 ERROR (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 559140 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 559140 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 559140 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 559140 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 559140 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-06T23:10:32.703396Z
[junit4] 2> 559141 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 559147 INFO (zkConnectionManagerCallback-5685-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 559147 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 559149 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 559153 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 559153 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 559154 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 559419 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 559420 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39f22d7f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 559420 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39f22d7f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 559423 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5d5c7b21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 559423 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5d5c7b21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 559423 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62756/solr
[junit4] 2> 559425 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 559430 INFO (zkConnectionManagerCallback-5696-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 559431 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 559551 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 559559 INFO (zkConnectionManagerCallback-5698-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 559559 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 559574 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 559583 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:62777_solr as DOWN
[junit4] 2> 559585 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 559585 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62777_solr
[junit4] 2> 559589 INFO (zkCallback-5636-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 559589 INFO (zkCallback-5624-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 559589 INFO (zkCallback-5674-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 559589 INFO (zkCallback-5659-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 559590 INFO (zkCallback-5697-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 559592 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 559593 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 559606 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 559623 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 559631 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 559631 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@575f7e67
[junit4] 2> 559632 INFO (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RoutingToNodesWithPropertiesTest_A322F68EEC9A21F9-001/tempDir-001/node4
[junit4] 2> 559635 WARN (TEST-RoutingToNodesWithPropertiesTest.test-seed#[A322F68EEC9A21F9]) [n:127.0.0.1:62777_solr ] o.a.s.s.SolrDispatchFilter Error registering JVM metrics
[junit4] 2> => java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.management/java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166)
[junit4] 2> java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
[junit4] 2> at java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage0(Native Method) ~[?:?]
[junit4] 2> at sun.management.MemoryPoolImpl.getCollectionUsage(MemoryPoolImpl.java:264) ~[?:?]
[junit4] 2> at com.codahale.metrics.jvm.MemoryUsageGaugeSet.getMetrics(MemoryUsageGaugeSet.java:96) ~[metrics-jvm-4.1.5.jar:4.1.5]
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.registerAll(SolrMetricManager.java:557) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.setupJvmMetrics(SolrDispatchFilter.java:216) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:186) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:134) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.lambda$initialize$0(ServletHandler.java:751) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at java.util.Spliterators$ArraySpliterator.forEachRemaining(Spliterators.java:948) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:734) ~[?:?]
[junit4] 2> at java.util.stream.ReferencePipeline$Head.forEach(ReferencePipeline.java:658) ~[?:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:744) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1448) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1507) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1164) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1003) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:459) ~[jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:382) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:193) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73) ~[jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.retryOnPortBindFailure(JettySolrRunner.java:566) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:504) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:472) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:471) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:482) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:79) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:972) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 559642 INFO (qtp717480193-7700) [n:127.0.0.1:62761_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 559643 INFO (qtp717480193-7692) [n:127.0.0.1:62761_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 559645 INFO (qtp1039880086-7697) [n:127.0.0.1:62762_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 559646 INFO (qtp1039880086-7699) [n:127.0.0.1:62762_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 559646 INFO (qtp1039880086-7699) [n:127.0.0.1:62762_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 559648 INFO (qtp276110254-7756) [n:127.0.0.1:62773_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 559649 INFO (qtp276110254-7757) [n:127.0.0.1:62773_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 559651 INFO (qtp1549803798-7784) [n:127.0.0.1:62777_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 559652 INFO (qtp1549803798-7785) [n:127.0.0.1:62777_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 559654 INFO (qtp717480193-7696) [n:127.0.0.1:62761_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 559655 INFO (qtp717480193-7698) [n:127.0.0.1:62761_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=coll&nrtReplicas=2&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 559661 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection coll
[junit4] 2> 559663 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll.AUTOCREATED/managed-schema
[junit4] 2> 559668 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll.AUTOCREATED/protwords.txt
[junit4] 2> 559673 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 559676 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/coll.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 559680 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/coll.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 559683 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/coll.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 559687 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/coll.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 559691 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/coll.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 559694 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/coll.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 559698 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/coll.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 559699 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/coll.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 559707 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/coll.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 559710 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/coll.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 559713 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/coll.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 559716 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/coll.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 559720 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/coll.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 559724 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/coll.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 559727 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/coll.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 559731 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/coll.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 559734 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/coll.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 559738 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/coll.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 559741 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/coll.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 559744 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/coll.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 559748 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/coll.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 559751 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/coll.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 559755 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/coll.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 559758 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/coll.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 559762 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/coll.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 559763 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/coll.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 559770 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/coll.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 559774 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/coll.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 559778 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/coll.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 559782 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/coll.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 559786 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/coll.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 559789 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/coll.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 559793 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/coll.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 559795 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/coll.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 559801 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/coll.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 559805 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/coll.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 559808 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/coll.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 559812 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/coll.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 559815 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/coll.AUTOCREATED/solrconfig.xml
[junit4] 2> 559818 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/coll.AUTOCREATED/synonyms.txt
[junit4] 2> 559821 INFO (OverseerThreadFactory-5644-thread-1-processing-n:127.0.0.1:62762_solr) [n:127.0.0.1:62762_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/coll.AUTOCREATED/stopwords.txt
[junit4] 2> 559936 INFO (qtp1039880086-7695) [n:127.0.0.1:62762_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 559937 INFO (qtp1039880086-7697) [n:127.0.0.1:62762_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 559938 INFO (qtp7174801
[...truncated too long message...]
etty-closer-5731-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 562745 INFO (jetty-closer-5731-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 562745 INFO (jetty-closer-5731-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5f5e60d6: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@769e741
[junit4] 2> 562746 INFO (jetty-closer-5731-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 562748 INFO (closeThreadPool-5754-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72060435034996742-127.0.0.1:62762_solr-n_0000000000) closing
[junit4] 2> 562748 INFO (OverseerStateUpdate-72060435034996742-127.0.0.1:62762_solr-n_0000000000) [n:127.0.0.1:62762_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62762_solr
[junit4] 2> 562750 INFO (OverseerAutoScalingTriggerThread-72060435034996742-127.0.0.1:62762_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 562756 INFO (jetty-closer-5731-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 562756 INFO (jetty-closer-5731-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@47be45b3: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@62a9be9f
[junit4] 2> 562759 INFO (jetty-closer-5731-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 562759 INFO (jetty-closer-5731-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@31e1d250: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@769e741
[junit4] 2> 562759 INFO (jetty-closer-5731-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 562761 INFO (closeThreadPool-5754-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72060435034996742-127.0.0.1:62762_solr-n_0000000000) closing
[junit4] 2> 562838 INFO (jetty-closer-5731-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@24040a29{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 562838 INFO (jetty-closer-5731-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@436a918f{/solr,null,UNAVAILABLE}
[junit4] 2> 562839 INFO (jetty-closer-5731-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 562863 INFO (zkCallback-5697-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 562864 INFO (jetty-closer-5731-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2d6335fe{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 562864 INFO (jetty-closer-5731-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4666868{/solr,null,UNAVAILABLE}
[junit4] 2> 562865 INFO (jetty-closer-5731-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 562971 INFO (jetty-closer-5731-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72060435034996742-127.0.0.1:62762_solr-n_0000000000) closing
[junit4] 2> 562975 INFO (jetty-closer-5731-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@333ca659{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 562975 INFO (jetty-closer-5731-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5adbd983{/solr,null,UNAVAILABLE}
[junit4] 2> 562975 INFO (jetty-closer-5731-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 563086 INFO (jetty-closer-5731-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3336ad80{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 563087 INFO (jetty-closer-5731-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3ef41eab{/solr,null,UNAVAILABLE}
[junit4] 2> 563087 INFO (jetty-closer-5731-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 563090 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 563311 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/collections/coll/terms/shard2
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 5 /solr/collections/coll/terms/shard1
[junit4] 2> 4 /solr/configs/coll.AUTOCREATED/managed-schema
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/coll.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 49 /solr/collections/coll/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2> 2 /solr/overseer_elect/election/72060435034996742-127.0.0.1:62762_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 30 /solr/overseer/queue
[junit4] 2> 21 /solr/live_nodes
[junit4] 2> 10 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 563316 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:62756
[junit4] 2> 563316 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62756
[junit4] 2> 563316 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[A322F68EEC9A21F9]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 62756
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RoutingToNodesWithPropertiesTest_A322F68EEC9A21F9-001
[junit4] 2> Jun 06, 2020 11:10:37 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=6076, maxDocsPerChunk=427, blockShift=2), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=6076, blockSize=2)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@11583209), locale=und, timezone=WET
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=6,threads=1,free=186666880,total=518664192
[junit4] 2> NOTE: All tests run in this JVM: [SpatialRPTFieldTypeTest, ZkStateReaderTest, HdfsLockFactoryTest, DistributedQueryComponentCustomSortTest, TestRestManager, TestConfigSetProperties, DebugComponentTest, TestCloudSchemaless, AssignBackwardCompatibilityTest, WrapperMergePolicyFactoryTest, TestRetrieveFieldsOptimizer, ChaosMonkeyNothingIsSafeWithPullReplicasTest, QueryElevationComponentTest, TestSort, TestRecovery, OverriddenZkACLAndCredentialsProvidersTest, TestExportTool, TestBlendedInfixSuggestions, PrimitiveFieldTypeTest, ScheduledTriggerIntegrationTest, XCJFQueryTest, PeerSyncTest, VMParamsZkACLAndCredentialsProvidersTest, TestWithCollection, IgnoreLargeDocumentProcessorFactoryTest, TestLeaderElectionWithEmptyReplica, OrderedExecutorTest, TestPayloadCheckQParserPlugin, TestBadConfig, CoreAdminOperationTest, SpellPossibilityIteratorTest, TestSolrTestCaseJ4, TestNumericTokenStream, TestMinHashQParser, TestRequestStatusCollectionAPI, TestJsonFacetRefinement, TestSolrCoreSnapshots, TestValueSourceCache, ShowFileRequestHandlerTest, TestSolrCloudWithSecureImpersonation, TestImpersonationWithHadoopAuth, RoutingToNodesWithPropertiesTest]
[junit4] Completed [230/910 (1!)] on J0 in 6.10s, 1 test, 1 failure <<< FAILURES!
[...truncated 49835 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1098/consoleText
[repro] Revision: f0683090797d3e0d3bb1799f2bdd3038f9114d79
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=RoutingToNodesWithPropertiesTest -Dtests.method=test -Dtests.seed=A322F68EEC9A21F9 -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=WET -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] RoutingToNodesWithPropertiesTest
[repro] ant compile-test
[...truncated 3475 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.RoutingToNodesWithPropertiesTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=A322F68EEC9A21F9 -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=WET -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 81 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
[repro] Exiting with code 0
[...truncated 73 lines...]