Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5908/
Java: 64bit/jdk-11.0.6 -XX:+UseCompressedOops -XX:+UseSerialGC

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.search.join.XCJFQueryTest

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.search.join.XCJFQueryTest:  
   1) Thread[id=793, name=SessionTracker, state=TIMED_WAITING, 
group=TGRP-XCJFQueryTest]         at 
[email protected]/java.lang.Thread.sleep(Native Method)         at 
app//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.search.join.XCJFQueryTest: 
   1) Thread[id=793, name=SessionTracker, state=TIMED_WAITING, 
group=TGRP-XCJFQueryTest]
        at [email protected]/java.lang.Thread.sleep(Native Method)
        at 
app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
        at __randomizedtesting.SeedInfo.seed([4C48650495CAF818]:0)




Build Log:
[...truncated 12798 lines...]
   [junit4] Suite: org.apache.solr.search.join.XCJFQueryTest
   [junit4]   2> 101635 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] o.a.s.SolrTestCase 
Setting 'solr.default.confdir' system property to test-framework derived value 
of 
'/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 101635 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 101636 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/data-dir-7-001
   [junit4]   2> 101636 WARN  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 101636 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 101637 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-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> 101638 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001
   [junit4]   2> 101638 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 101640 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 101640 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 101740 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:61023
   [junit4]   2> 101741 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:61023
   [junit4]   2> 101741 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61023
   [junit4]   2> 101741 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 61023
   [junit4]   2> 101744 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101750 INFO  (zkConnectionManagerCallback-530-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 101750 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101757 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101761 INFO  (zkConnectionManagerCallback-532-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 101761 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101763 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101767 INFO  (zkConnectionManagerCallback-534-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 101767 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101881 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 101881 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 101881 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 101881 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 101881 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 101881 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 101881 INFO  (jetty-launcher-535-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> 101881 INFO  (jetty-launcher-535-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> 101881 INFO  (jetty-launcher-535-thread-3) [     ] 
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> 101890 INFO  (jetty-launcher-535-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 101890 INFO  (jetty-launcher-535-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 101890 INFO  (jetty-launcher-535-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 101891 INFO  (jetty-launcher-535-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6bb3748{/solr,null,AVAILABLE}
   [junit4]   2> 101894 INFO  (jetty-launcher-535-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@344fdd0{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:61029}
   [junit4]   2> 101894 INFO  (jetty-launcher-535-thread-1) [     ] 
o.e.j.s.Server Started @102185ms
   [junit4]   2> 101894 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=61029}
   [junit4]   2> 101894 INFO  (jetty-launcher-535-thread-3) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 101894 INFO  (jetty-launcher-535-thread-3) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 101894 INFO  (jetty-launcher-535-thread-3) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 101895 ERROR (jetty-launcher-535-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-04-23T01:34:12.612178Z
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-3) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@503ffd2{/solr,null,AVAILABLE}
   [junit4]   2> 101895 INFO  (jetty-launcher-535-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@cf3d743{/solr,null,AVAILABLE}
   [junit4]   2> 101896 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101897 INFO  (jetty-launcher-535-thread-3) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@f03ae1d{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:61030}
   [junit4]   2> 101897 INFO  (jetty-launcher-535-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2a1f533{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:61031}
   [junit4]   2> 101897 INFO  (jetty-launcher-535-thread-2) [     ] 
o.e.j.s.Server Started @102187ms
   [junit4]   2> 101897 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=61031}
   [junit4]   2> 101897 INFO  (jetty-launcher-535-thread-3) [     ] 
o.e.j.s.Server Started @102188ms
   [junit4]   2> 101897 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=61030}
   [junit4]   2> 101898 ERROR (jetty-launcher-535-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 101898 ERROR (jetty-launcher-535-thread-3) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-04-23T01:34:12.615217Z
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
   [junit4]   2> 101898 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-04-23T01:34:12.615370Z
   [junit4]   2> 101899 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101900 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101901 INFO  (zkConnectionManagerCallback-537-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 101901 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101904 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 101905 INFO  (zkConnectionManagerCallback-541-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 101906 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101907 INFO  (zkConnectionManagerCallback-539-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 101907 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101908 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 101908 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 101911 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 101912 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 101912 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 102121 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 102122 WARN  (jetty-launcher-535-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@18df1814[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102122 WARN  (jetty-launcher-535-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@18df1814[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102126 WARN  (jetty-launcher-535-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4cfe5128[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102126 WARN  (jetty-launcher-535-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4cfe5128[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102130 INFO  (jetty-launcher-535-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61023/solr
   [junit4]   2> 102134 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 102143 INFO  (zkConnectionManagerCallback-555-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 102143 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 102290 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 102292 WARN  (jetty-launcher-535-thread-3) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2abc8e96[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102292 WARN  (jetty-launcher-535-thread-3) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2abc8e96[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102296 WARN  (jetty-launcher-535-thread-3) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@ab2bf7e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102296 WARN  (jetty-launcher-535-thread-3) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@ab2bf7e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102299 INFO  (jetty-launcher-535-thread-3) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61023/solr
   [junit4]   2> 102302 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 102309 INFO  (zkConnectionManagerCallback-568-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 102309 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 102386 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:61030_solr
   [junit4]   2> 102392 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.Overseer Overseer 
(id=72094389698691079-127.0.0.1:61030_solr-n_0000000000) starting
   [junit4]   2> 102431 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 102459 WARN  (jetty-launcher-535-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7acc9f7[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102459 WARN  (jetty-launcher-535-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@7acc9f7[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102461 INFO  
(OverseerStateUpdate-72094389698691079-127.0.0.1:61030_solr-n_0000000000) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:61030_solr
   [junit4]   2> 102464 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:61030_solr
   [junit4]   2> 102470 WARN  (jetty-launcher-535-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@464fd409[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102470 WARN  (jetty-launcher-535-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@464fd409[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 102479 INFO  (jetty-launcher-535-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61023/solr
   [junit4]   2> 102496 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 102496 INFO  (zkCallback-567-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 102513 INFO  (zkConnectionManagerCallback-583-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 102516 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 102561 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 102562 WARN  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_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> 102604 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 102635 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 102650 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102659 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102659 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102660 INFO  (jetty-launcher-535-thread-3) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node3/.
   [junit4]   2> 102665 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:61031_solr as DOWN
   [junit4]   2> 102671 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 4 transient cores
   [junit4]   2> 102671 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:61031_solr
   [junit4]   2> 102676 INFO  (zkCallback-582-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 102676 INFO  (zkCallback-567-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 102697 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 102699 WARN  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_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> 102781 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 102804 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102813 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102813 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102816 INFO  (jetty-launcher-535-thread-2) 
[n:127.0.0.1:61031_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node2/.
   [junit4]   2> 102863 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 102876 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:61029_solr as DOWN
   [junit4]   2> 102879 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 4 transient cores
   [junit4]   2> 102879 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:61029_solr
   [junit4]   2> 102883 INFO  (zkCallback-582-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 102884 INFO  (zkCallback-567-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 102884 INFO  (zkCallback-554-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 102895 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 102897 WARN  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_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> 102952 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 102978 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102987 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102988 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 102993 INFO  (jetty-launcher-535-thread-1) 
[n:127.0.0.1:61029_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node1/.
   [junit4]   2> 103013 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
   [junit4]   2> 103014 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 103023 INFO  (zkConnectionManagerCallback-607-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103023 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 103039 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 103043 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61023/solr ready
   [junit4]   2> 103071 INFO  (qtp226693648-831) [n:127.0.0.1:61029_solr     ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=xcjf&name=products&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 103087 INFO  
(OverseerThreadFactory-580-thread-1-processing-n:127.0.0.1:61030_solr) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
products
   [junit4]   2> 103240 INFO  (qtp226693648-833) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 103246 INFO  (qtp208018127-839) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 103251 INFO  (qtp29638741-840) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=1
   [junit4]   2> 103259 INFO  (qtp226693648-830) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 103264 INFO  (qtp208018127-841) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=2
   [junit4]   2> 103269 INFO  (qtp29638741-842) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 103375 INFO  
(OverseerStateUpdate-72094389698691079-127.0.0.1:61030_solr-n_0000000000) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"products",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"products_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61030/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:61030_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 103397 INFO  
(OverseerStateUpdate-72094389698691079-127.0.0.1:61030_solr-n_0000000000) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"products",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"products_shard2_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61031/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:61031_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 103406 INFO  
(OverseerStateUpdate-72094389698691079-127.0.0.1:61030_solr-n_0000000000) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"products",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"products_shard3_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61029/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:61029_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 103625 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr    
x:products_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=xcjf&newCollection=true&name=products_shard1_replica_n1&action=CREATE&numShards=3&collection=products&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 103628 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr    
x:products_shard2_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=xcjf&newCollection=true&name=products_shard2_replica_n3&action=CREATE&numShards=3&collection=products&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 103628 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr    
x:products_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node6&collection.configName=xcjf&newCollection=true&name=products_shard3_replica_n4&action=CREATE&numShards=3&collection=products&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 103629 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr    
x:products_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 4 transient cores
   [junit4]   2> 103683 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 103683 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 103683 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 103723 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 103723 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 103723 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 103731 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.IndexSchema Schema name=xcjf
   [junit4]   2> 103731 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.IndexSchema Schema name=xcjf
   [junit4]   2> 103731 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.IndexSchema Schema name=xcjf
   [junit4]   2> 103733 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.IndexSchema Loaded schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 103733 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.IndexSchema Loaded schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 103733 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.IndexSchema Loaded schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 103738 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.CoreContainer Creating SolrCore 'products_shard2_replica_n3' using 
configuration from configset xcjf, trusted=true
   [junit4]   2> 103739 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.CoreContainer Creating SolrCore 'products_shard3_replica_n4' using 
configuration from configset xcjf, trusted=true
   [junit4]   2> 103739 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.products.shard2.replica_n3' (registry 
'solr.core.products.shard2.replica_n3') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 103744 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.products.shard3.replica_n4' (registry 
'solr.core.products.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 103745 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.SolrCore [[products_shard2_replica_n3] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node2/./products_shard2_replica_n3],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node2/./products_shard2_replica_n3/data/]
   [junit4]   2> 103745 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.SolrCore [[products_shard3_replica_n4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node1/./products_shard3_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node1/./products_shard3_replica_n4/data/]
   [junit4]   2> 103757 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at 
/configs/xcjf/managed-schema
   [junit4]   2> 103790 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, renamed the non-managed schema /configs/xcjf/schema.xml to 
/configs/xcjf/schema.xml.bak
   [junit4]   2> 103794 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.CoreContainer Creating SolrCore 'products_shard1_replica_n1' using 
configuration from configset xcjf, trusted=true
   [junit4]   2> 103795 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.products.shard1.replica_n1' (registry 
'solr.core.products.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 103795 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.SolrCore [[products_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node3/./products_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node3/./products_shard1_replica_n1/data/]
   [junit4]   2> 103959 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 103959 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 103967 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4c870acc[products_shard3_replica_n4] main]
   [junit4]   2> 103979 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/xcjf
   [junit4]   2> 103980 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/xcjf
   [junit4]   2> 103982 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 103982 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 103984 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/xcjf/managed-schema
   [junit4]   2> 103988 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1b38d8ec[products_shard2_replica_n3] main]
   [junit4]   2> 103989 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from Zookeeper
   [junit4]   2> 103994 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/xcjf
   [junit4]   2> 103995 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.IndexSchema Schema name=xcjf
   [junit4]   2> 103995 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/xcjf
   [junit4]   2> 103995 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/xcjf/managed-schema
   [junit4]   2> 104005 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from Zookeeper
   [junit4]   2> 104017 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.IndexSchema Loaded schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 104017 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 27 ms
   [junit4]   2> 104017 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 104018 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 104018 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 104018 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.IndexSchema Schema name=xcjf
   [junit4]   2> 104021 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@16d3fd45[products_shard1_replica_n1] main]
   [junit4]   2> 104021 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.IndexSchema Loaded schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 104021 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 16 ms
   [junit4]   2> 104021 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 104026 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/xcjf
   [junit4]   2> 104027 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/xcjf
   [junit4]   2> 104028 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/xcjf/managed-schema
   [junit4]   2> 104036 INFO  
(searcherExecutor-611-thread-1-processing-n:127.0.0.1:61029_solr 
x:products_shard3_replica_n4 c:products s:shard3 r:core_node6) 
[n:127.0.0.1:61029_solr c:products s:shard3 r:core_node6 
x:products_shard3_replica_n4 ] o.a.s.c.SolrCore [products_shard3_replica_n4] 
Registered new searcher Searcher@4c870acc[products_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 104036 INFO  
(searcherExecutor-609-thread-1-processing-n:127.0.0.1:61031_solr 
x:products_shard2_replica_n3 c:products s:shard2 r:core_node5) 
[n:127.0.0.1:61031_solr c:products s:shard2 r:core_node5 
x:products_shard2_replica_n3 ] o.a.s.c.SolrCore [products_shard2_replica_n3] 
Registered new searcher Searcher@1b38d8ec[products_shard2_replica_n3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 104036 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 104037 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 104042 INFO  
(searcherExecutor-613-thread-1-processing-n:127.0.0.1:61030_solr 
x:products_shard1_replica_n1 c:products s:shard1 r:core_node2) 
[n:127.0.0.1:61030_solr c:products s:shard1 r:core_node2 
x:products_shard1_replica_n1 ] o.a.s.c.SolrCore [products_shard1_replica_n1] 
Registered new searcher Searcher@16d3fd45[products_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 104058 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/products/terms/shard1 to Terms{values={core_node2=0}, version=0}
   [junit4]   2> 104059 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/products/terms/shard2 to Terms{values={core_node5=0}, version=0}
   [junit4]   2> 104058 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/products/terms/shard3 to Terms{values={core_node6=0}, version=0}
   [junit4]   2> 104065 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/products/leaders/shard2
   [junit4]   2> 104065 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/products/leaders/shard1
   [junit4]   2> 104065 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/products/leaders/shard3
   [junit4]   2> 104084 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 104084 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 104084 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 104084 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 104084 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 104084 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 104085 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:61031/solr/products_shard2_replica_n3/
   [junit4]   2> 104085 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:61030/solr/products_shard1_replica_n1/
   [junit4]   2> 104085 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:61029/solr/products_shard3_replica_n4/
   [junit4]   2> 104085 ERROR (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
   [junit4]   2> 104085 ERROR (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
   [junit4]   2> 104085 ERROR (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
   [junit4]   2> 104085 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
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> 104085 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_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> 104085 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
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> 104087 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/products/leaders/shard1/leader after winning as 
/collections/products/leader_elect/shard1/election/72094389698691079-core_node2-n_0000000000
   [junit4]   2> 104087 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/products/leaders/shard3/leader after winning as 
/collections/products/leader_elect/shard3/election/72094389698691078-core_node6-n_0000000000
   [junit4]   2> 104087 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/products/leaders/shard2/leader after winning as 
/collections/products/leader_elect/shard2/election/72094389698691080-core_node5-n_0000000000
   [junit4]   2> 104101 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:61031/solr/products_shard2_replica_n3/ shard2
   [junit4]   2> 104103 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:61029/solr/products_shard3_replica_n4/ shard3
   [junit4]   2> 104103 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:61030/solr/products_shard1_replica_n1/ shard1
   [junit4]   2> 104223 INFO  (zkCallback-554-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104223 INFO  (zkCallback-567-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104224 INFO  (zkCallback-554-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104225 INFO  (zkCallback-567-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104229 INFO  (zkCallback-582-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104230 INFO  (zkCallback-582-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104230 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:products s:shard3 r:core_node6 x:products_shard3_replica_n4 ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 104230 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr 
c:products s:shard1 r:core_node2 x:products_shard1_replica_n1 ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 104234 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr 
c:products s:shard2 r:core_node5 x:products_shard2_replica_n3 ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 104242 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=xcjf&newCollection=true&name=products_shard3_replica_n4&action=CREATE&numShards=3&collection=products&shard=shard3&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=613
   [junit4]   2> 104242 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=xcjf&newCollection=true&name=products_shard2_replica_n3&action=CREATE&numShards=3&collection=products&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=616
   [junit4]   2> 104242 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=xcjf&newCollection=true&name=products_shard1_replica_n1&action=CREATE&numShards=3&collection=products&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=618
   [junit4]   2> 104249 INFO  (qtp226693648-831) [n:127.0.0.1:61029_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> 104353 INFO  (zkCallback-567-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104353 INFO  (zkCallback-567-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104353 INFO  (zkCallback-567-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104353 INFO  (zkCallback-582-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104353 INFO  (zkCallback-554-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104353 INFO  (zkCallback-582-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104354 INFO  (zkCallback-554-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104354 INFO  (zkCallback-582-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104355 INFO  (zkCallback-554-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/products/state.json] 
for collection [products] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 104356 INFO  (qtp226693648-831) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collection.configName=xcjf&name=products&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2}
 status=0 QTime=1285
   [junit4]   2> 104360 INFO  (qtp226693648-833) [n:127.0.0.1:61029_solr     ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=xcjf&name=parts&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 104369 INFO  
(OverseerThreadFactory-580-thread-2-processing-n:127.0.0.1:61030_solr) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
parts
   [junit4]   2> 104370 INFO  
(OverseerCollectionConfigSetProcessor-72094389698691079-127.0.0.1:61030_solr-n_0000000000)
 [n:127.0.0.1:61030_solr     ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 104598 INFO  (qtp226693648-830) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.products.shard3.replica_n4:INDEX.sizeInBytes}
 status=0 QTime=4
   [junit4]   2> 104602 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=1
   [junit4]   2> 104604 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.products.shard2.replica_n3:INDEX.sizeInBytes}
 status=0 QTime=1
   [junit4]   2> 104607 INFO  (qtp208018127-839) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=1
   [junit4]   2> 104609 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.products.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 104612 INFO  (qtp29638741-840) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=1
   [junit4]   2> 104616 INFO  (qtp226693648-831) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.products.shard3.replica_n4:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 104618 INFO  (qtp226693648-830) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=1
   [junit4]   2> 104620 INFO  (qtp208018127-841) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.products.shard2.replica_n3:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 104623 INFO  (qtp208018127-843) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=1
   [junit4]   2> 104627 INFO  (qtp29638741-842) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.products.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=1
   [junit4]   2> 104629 INFO  (qtp29638741-844) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 104651 INFO  
(OverseerStateUpdate-72094389698691079-127.0.0.1:61030_solr-n_0000000000) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"parts",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"parts_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61030/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:61030_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 104658 INFO  
(OverseerStateUpdate-72094389698691079-127.0.0.1:61030_solr-n_0000000000) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"parts",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"parts_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61031/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:61031_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 104664 INFO  
(OverseerStateUpdate-72094389698691079-127.0.0.1:61030_solr-n_0000000000) 
[n:127.0.0.1:61030_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"parts",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"parts_shard3_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61029/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:61029_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 104881 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr    
x:parts_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node6&collection.configName=xcjf&newCollection=true&name=parts_shard3_replica_n4&action=CREATE&numShards=3&collection=parts&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 104881 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr    
x:parts_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=xcjf&newCollection=true&name=parts_shard1_replica_n1&action=CREATE&numShards=3&collection=parts&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 104881 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr    
x:parts_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=xcjf&newCollection=true&name=parts_shard2_replica_n2&action=CREATE&numShards=3&collection=parts&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 104906 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 104926 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 104927 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 104930 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.s.IndexSchema 
Schema name=xcjf
   [junit4]   2> 104941 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.s.IndexSchema 
Loaded schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 104941 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.c.CoreContainer 
Creating SolrCore 'parts_shard3_replica_n4' using configuration from configset 
xcjf, trusted=true
   [junit4]   2> 104942 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.parts.shard3.replica_n4' (registry 
'solr.core.parts.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 104942 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.c.SolrCore 
[[parts_shard3_replica_n4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node1/./parts_shard3_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node1/./parts_shard3_replica_n4/data/]
   [junit4]   2> 104945 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=xcjf
   [junit4]   2> 104945 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.s.IndexSchema 
Schema name=xcjf
   [junit4]   2> 104946 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded 
schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 104946 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.s.IndexSchema 
Loaded schema xcjf/1.1 with uniqueid field id
   [junit4]   2> 104946 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.c.CoreContainer 
Creating SolrCore 'parts_shard2_replica_n2' using configuration from configset 
xcjf, trusted=true
   [junit4]   2> 104946 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.c.CoreContainer 
Creating SolrCore 'parts_shard1_replica_n1' using configuration from configset 
xcjf, trusted=true
   [junit4]   2> 104947 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr.core.parts.shard1.replica_n1' (registry 
'solr.core.parts.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 104947 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.parts.shard2.replica_n2' (registry 
'solr.core.parts.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5f554ad5
   [junit4]   2> 104947 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[parts_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node3/./parts_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node3/./parts_shard1_replica_n1/data/]
   [junit4]   2> 104947 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.c.SolrCore 
[[parts_shard2_replica_n2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node2/./parts_shard2_replica_n2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J3/temp/solr.search.join.XCJFQueryTest_4C48650495CAF818-001/tempDir-001/node2/./parts_shard2_replica_n2/data/]
   [junit4]   2> 105056 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 105056 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 105059 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4aac19a2[parts_shard3_replica_n4] 
main]
   [junit4]   2> 105062 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/xcjf
   [junit4]   2> 105063 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/xcjf
   [junit4]   2> 105063 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/xcjf/managed-schema
   [junit4]   2> 105065 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 105066 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 105069 INFO  
(searcherExecutor-624-thread-1-processing-n:127.0.0.1:61029_solr 
x:parts_shard3_replica_n4 c:parts s:shard3 r:core_node6) 
[n:127.0.0.1:61029_solr c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 
] o.a.s.c.SolrCore [parts_shard3_replica_n4] Registered new searcher 
Searcher@4aac19a2[parts_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 105073 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 105073 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 105077 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@53490eef[parts_shard1_replica_n1] main]
   [junit4]   2> 105078 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.c.ZkShardTerms 
Successful update of terms at /collections/parts/terms/shard3 to 
Terms{values={core_node6=0}, version=0}
   [junit4]   2> 105078 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/parts/leaders/shard3
   [junit4]   2> 105081 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 105081 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/xcjf
   [junit4]   2> 105081 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 105082 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/xcjf
   [junit4]   2> 105082 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader 
Creating ZooKeeper watch for the managed schema at /configs/xcjf/managed-schema
   [junit4]   2> 105085 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader 
Current schema version 0 is already the latest
   [junit4]   2> 105086 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 105088 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1114019b[parts_shard2_replica_n2] 
main]
   [junit4]   2> 105091 INFO  
(searcherExecutor-628-thread-1-processing-n:127.0.0.1:61030_solr 
x:parts_shard1_replica_n1 c:parts s:shard1 r:core_node3) 
[n:127.0.0.1:61030_solr c:parts s:shard1 r:core_node3 x:parts_shard1_replica_n1 
] o.a.s.c.SolrCore [parts_shard1_replica_n1] Registered new searcher 
Searcher@53490eef[parts_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 105093 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/xcjf
   [junit4]   2> 105094 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/xcjf
   [junit4]   2> 105094 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/xcjf/managed-schema
   [junit4]   2> 105095 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 105096 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 105096 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:61029/solr/parts_shard3_replica_n4/
   [junit4]   2> 105096 ERROR (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.c.SyncStrategy 
No UpdateLog found - cannot sync
   [junit4]   2> 105096 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
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> 105096 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/parts/leaders/shard3/leader after winning as 
/collections/parts/leader_elect/shard3/election/72094389698691078-core_node6-n_0000000000
   [junit4]   2> 105097 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 105097 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 105102 INFO  
(searcherExecutor-627-thread-1-processing-n:127.0.0.1:61031_solr 
x:parts_shard2_replica_n2 c:parts s:shard2 r:core_node5) 
[n:127.0.0.1:61031_solr c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 
] o.a.s.c.SolrCore [parts_shard2_replica_n2] Registered new searcher 
Searcher@1114019b[parts_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 105105 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.c.ZkShardTerms 
Successful update of terms at /collections/parts/terms/shard1 to 
Terms{values={core_node3=0}, version=0}
   [junit4]   2> 105106 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:61029/solr/parts_shard3_replica_n4/ shard3
   [junit4]   2> 105106 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/parts/leaders/shard1
   [junit4]   2> 105115 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.c.ZkShardTerms 
Successful update of terms at /collections/parts/terms/shard2 to 
Terms{values={core_node5=0}, version=0}
   [junit4]   2> 105116 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/parts/leaders/shard2
   [junit4]   2> 105122 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 105122 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 105123 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync 
replicas to http://127.0.0.1:61030/solr/parts_shard1_replica_n1/
   [junit4]   2> 105123 ERROR (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.c.SyncStrategy No 
UpdateLog found - cannot sync
   [junit4]   2> 105123 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_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> 105123 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/parts/leaders/shard1/leader after winning as 
/collections/parts/leader_elect/shard1/election/72094389698691079-core_node3-n_0000000000
   [junit4]   2> 105130 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:61030/solr/parts_shard1_replica_n1/ shard1
   [junit4]   2> 105131 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 105131 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 105131 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:61031/solr/parts_shard2_replica_n2/
   [junit4]   2> 105131 ERROR (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.c.SyncStrategy 
No UpdateLog found - cannot sync
   [junit4]   2> 105131 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
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> 105132 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/parts/leaders/shard2/leader after winning as 
/collections/parts/leader_elect/shard2/election/72094389698691080-core_node5-n_0000000000
   [junit4]   2> 105137 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:61031/solr/parts_shard2_replica_n2/ shard2
   [junit4]   2> 105251 INFO  (zkCallback-554-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105251 INFO  (zkCallback-554-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105252 INFO  (zkCallback-567-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105252 INFO  (zkCallback-567-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105252 INFO  (zkCallback-582-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105252 INFO  (zkCallback-582-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105255 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr c:parts 
s:shard1 r:core_node3 x:parts_shard1_replica_n1 ] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 105256 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr 
c:parts s:shard3 r:core_node6 x:parts_shard3_replica_n4 ] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 105256 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr 
c:parts s:shard2 r:core_node5 x:parts_shard2_replica_n2 ] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 105266 INFO  (qtp226693648-832) [n:127.0.0.1:61029_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=xcjf&newCollection=true&name=parts_shard3_replica_n4&action=CREATE&numShards=3&collection=parts&shard=shard3&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=385
   [junit4]   2> 105267 INFO  (qtp208018127-837) [n:127.0.0.1:61031_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=xcjf&newCollection=true&name=parts_shard2_replica_n2&action=CREATE&numShards=3&collection=parts&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=385
   [junit4]   2> 105267 INFO  (qtp29638741-838) [n:127.0.0.1:61030_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=xcjf&newCollection=true&name=parts_shard1_replica_n1&action=CREATE&numShards=3&collection=parts&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=386
   [junit4]   2> 105272 INFO  (qtp226693648-833) [n:127.0.0.1:61029_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> 105385 INFO  (zkCallback-554-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105385 INFO  (zkCallback-567-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/parts/state.json] 
for collection [parts] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 105385 INFO  (zkCallback-582-thread-2) [

[...truncated too long message...]

er Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4d348ed4: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry 
solr.jvm/com.codahale.metrics.MetricRegistry@67f46f26
   [junit4]   2> 138095 INFO  (jetty-closer-867-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 138095 INFO  (jetty-closer-867-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@82f8aa2: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty/com.codahale.metrics.MetricRegistry@26c54a0c
   [junit4]   2> 138095 INFO  (jetty-closer-867-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster 
tag=null
   [junit4]   2> 138096 INFO  (jetty-closer-867-thread-3) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 138096 INFO  (jetty-closer-867-thread-3) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6f361190: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty/com.codahale.metrics.MetricRegistry@26c54a0c
   [junit4]   2> 138096 INFO  (jetty-closer-867-thread-3) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster 
tag=null
   [junit4]   2> 138181 INFO  (jetty-closer-867-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72094389698691079-127.0.0.1:61030_solr-n_0000000000) closing
   [junit4]   2> 138187 INFO  (jetty-closer-867-thread-1) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@f03ae1d{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 138188 INFO  (jetty-closer-867-thread-1) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@503ffd2{/solr,null,UNAVAILABLE}
   [junit4]   2> 138189 INFO  (jetty-closer-867-thread-1) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 138230 INFO  (jetty-closer-867-thread-3) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@344fdd0{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 138232 INFO  (jetty-closer-867-thread-3) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6bb3748{/solr,null,UNAVAILABLE}
   [junit4]   2> 138232 INFO  (jetty-closer-867-thread-3) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 138332 INFO  (jetty-closer-867-thread-2) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@2a1f533{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 138333 INFO  (jetty-closer-867-thread-2) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@cf3d743{/solr,null,UNAVAILABLE}
   [junit4]   2> 138334 INFO  (jetty-closer-867-thread-2) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 138337 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 138552 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>        30      /solr/clusterprops.json
   [junit4]   2>        30      /solr/aliases.json
   [junit4]   2>        18      /solr/configs/xcjf/managed-schema
   [junit4]   2>        3       /solr/packages.json
   [junit4]   2>        3       /solr/security.json
   [junit4]   2>        3       /solr/collections/products/collectionprops.json
   [junit4]   2>        3       /solr/configs/xcjf
   [junit4]   2>        3       /solr/collections/parts/collectionprops.json
   [junit4]   2>        2       /solr/collections/products/terms/shard2
   [junit4]   2>        2       /solr/collections/products/terms/shard1
   [junit4]   2>        2       /solr/collections/parts/terms/shard3
   [junit4]   2>        2       /solr/collections/parts/terms/shard1
   [junit4]   2>        2       /solr/collections/parts/terms/shard2
   [junit4]   2>        2       /solr/collections/products/terms/shard3
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        45      /solr/collections/parts/state.json
   [junit4]   2>        45      /solr/collections/products/state.json
   [junit4]   2>        30      /solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        38      /solr/live_nodes
   [junit4]   2>        38      /solr/collections
   [junit4]   2>        33      /solr/overseer/queue
   [junit4]   2>        20      /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 138558 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:61023
   [junit4]   2> 138558 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61023
   [junit4]   2> 138558 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 61023
   [junit4]   2> 138559 INFO  
(SUITE-XCJFQueryTest-seed#[4C48650495CAF818]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> Apr 23, 2020 1:34:49 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> Apr 23, 2020 1:34:59 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> SEVERE: 1 thread leaked from SUITE scope at 
org.apache.solr.search.join.XCJFQueryTest: 
   [junit4]   2>    1) Thread[id=793, name=SessionTracker, state=TIMED_WAITING, 
group=TGRP-XCJFQueryTest]
   [junit4]   2>         at [email protected]/java.lang.Thread.sleep(Native 
Method)
   [junit4]   2>         at 
app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
   [junit4]   2> Apr 23, 2020 1:34:59 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=793, name=SessionTracker, state=TIMED_WAITING, 
group=TGRP-XCJFQueryTest]
   [junit4]   2> 148722 ERROR (SessionTracker) [     ] 
o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : 
SessionTracker
   [junit4]   2>           => java.lang.InterruptedException: sleep interrupted
   [junit4]   2>        at java.base/java.lang.Thread.sleep(Native Method)
   [junit4]   2> java.lang.InterruptedException: sleep interrupted
   [junit4]   2>        at java.lang.Thread.sleep(Native Method) [?:?]
   [junit4]   2>        at 
org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) 
~[zookeeper-3.5.7.jar:3.5.7]
   [junit4]   2> Apr 23, 2020 1:34:59 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: All leaked threads terminated.
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): 
{size_s=PostingsFormat(name=Asserting), id=BlockTreeOrds(blocksize=128), 
product_id_s=BlockTreeOrds(blocksize=128)}, 
docValues:{product_id_i=DocValuesFormat(name=Asserting), 
size_s=DocValuesFormat(name=Asserting), 
product_id_l=DocValuesFormat(name=Lucene80), 
id=DocValuesFormat(name=Asserting), 
product_id_s=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1795, 
maxMBSortInHeap=6.819049152145164, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4dbb7786),
 locale=en-UG, timezone=America/St_Lucia
   [junit4]   2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 11.0.6 
(64-bit)/cpus=6,threads=1,free=51377920,total=194707456
   [junit4]   2> NOTE: All tests run in this JVM: [OverseerRolesTest, 
TestPhraseSuggestions, NodeMarkersRegistrationTest, TriggerEventQueueTest, 
PropertiesRequestHandlerTest, PathHierarchyTokenizerFactoryTest, XCJFQueryTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=XCJFQueryTest 
-Dtests.seed=4C48650495CAF818 -Dtests.slow=true -Dtests.locale=en-UG 
-Dtests.timezone=America/St_Lucia -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J3 | XCJFQueryTest (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.search.join.XCJFQueryTest: 
   [junit4]    >    1) Thread[id=793, name=SessionTracker, state=TIMED_WAITING, 
group=TGRP-XCJFQueryTest]
   [junit4]    >         at [email protected]/java.lang.Thread.sleep(Native 
Method)
   [junit4]    >         at 
app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4C48650495CAF818]:0)
   [junit4] Completed [22/909 (1!)] on J3 in 47.13s, 3 tests, 1 error <<< 
FAILURES!

[...truncated 55690 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5908/consoleText

[repro] Revision: 3a743ea953f0ecfc35fc7b198f68d142ce99d789

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line:  ant test  -Dtestcase=XCJFQueryTest 
-Dtests.seed=4C48650495CAF818 -Dtests.slow=true -Dtests.locale=en-UG 
-Dtests.timezone=America/St_Lucia -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII

[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]       XCJFQueryTest
[repro] ant compile-test

[...truncated 3152 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.XCJFQueryTest" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=4C48650495CAF818 
-Dtests.slow=true -Dtests.locale=en-UG -Dtests.timezone=America/St_Lucia 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII

[...truncated 91 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: org.apache.solr.search.join.XCJFQueryTest
[repro] Exiting with code 0

[...truncated 73 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to