Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/3239/

1 tests failed.
FAILED:  
org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest.testSliceRouting

Error Message:
Error from server at 
https://127.0.0.1:41953/solr/myAlias__CRA__Heart_of_Gold_shard3_replica_n14: 
Could not find collection : myAlias__CRA__Stunt_Ship

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at 
https://127.0.0.1:41953/solr/myAlias__CRA__Heart_of_Gold_shard3_replica_n14: 
Could not find collection : myAlias__CRA__Stunt_Ship
        at 
__randomizedtesting.SeedInfo.seed([CE854C3303CD16:315E09A09755323F]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.directUpdate(BaseCloudSolrClient.java:493)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:964)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:830)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:763)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
        at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:177)
        at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
        at 
org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest.testSliceRouting(CategoryRoutedAliasUpdateProcessorTest.java:366)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 15585 lines...]
   [junit4] Suite: 
org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/init-core-data-001
   [junit4]   2> 2479807 WARN  
(SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[CE854C3303CD16]-worker) [   
 ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=116 numCloses=116
   [junit4]   2> 2479807 INFO  
(SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[CE854C3303CD16]-worker) [   
 ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2479810 INFO  
(SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[CE854C3303CD16]-worker) [   
 ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2479810 INFO  
(SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[CE854C3303CD16]-worker) [   
 ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2479814 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMustMatch
   [junit4]   2> 2479814 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001
   [junit4]   2> 2479814 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2479815 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2479815 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2479915 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.ZkTestServer start zk server on port:44030
   [junit4]   2> 2479915 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44030
   [junit4]   2> 2479915 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44030
   [junit4]   2> 2479926 INFO  (zkConnectionManagerCallback-20112-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2479931 INFO  (zkConnectionManagerCallback-20114-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2479933 INFO  (zkConnectionManagerCallback-20116-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2479937 WARN  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2479938 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2479938 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2479938 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 2479939 WARN  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2479940 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2479940 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2479940 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 2479941 WARN  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2479941 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2479941 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2479941 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 2479943 WARN  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2479943 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2479943 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2479943 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 2479944 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2479944 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2479944 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2479944 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4654062a{/solr,null,AVAILABLE}
   [junit4]   2> 2479945 WARN  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@234647f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2479949 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2479949 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2479949 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2479949 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@e13962d{/solr,null,AVAILABLE}
   [junit4]   2> 2479950 WARN  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@64c4a751[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2479951 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2479951 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2479951 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2479951 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4e35adb9{/solr,null,AVAILABLE}
   [junit4]   2> 2479952 WARN  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@75ad6a3[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2479953 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@20ffadff{SSL,[ssl, 
http/1.1]}{127.0.0.1:39051}
   [junit4]   2> 2479953 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.s.Server Started @2480007ms
   [junit4]   2> 2479953 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39051}
   [junit4]   2> 2479953 ERROR (jetty-launcher-20117-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2479953 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-04-01T07:12:42.022Z
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2479954 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@41a2388a{/solr,null,AVAILABLE}
   [junit4]   2> 2479955 WARN  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@6b5661ad[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@5274ca1e{SSL,[ssl, 
http/1.1]}{127.0.0.1:44787}
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.s.Server Started @2480009ms
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=44787}
   [junit4]   2> 2479955 ERROR (jetty-launcher-20117-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2479955 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-04-01T07:12:42.023Z
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@3fb44794{SSL,[ssl, 
http/1.1]}{127.0.0.1:46486}
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.s.Server Started @2480010ms
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46486}
   [junit4]   2> 2479956 ERROR (jetty-launcher-20117-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2479956 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-04-01T07:12:42.024Z
   [junit4]   2> 2479958 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@3ebf0ac3{SSL,[ssl, 
http/1.1]}{127.0.0.1:38032}
   [junit4]   2> 2479958 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.s.Server Started @2480012ms
   [junit4]   2> 2479958 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38032}
   [junit4]   2> 2479958 ERROR (jetty-launcher-20117-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2479958 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2479960 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 2479960 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2479960 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2479960 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-04-01T07:12:42.028Z
   [junit4]   2> 2479962 INFO  (zkConnectionManagerCallback-20121-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2479963 INFO  (zkConnectionManagerCallback-20123-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2479963 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2479968 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2479983 INFO  (zkConnectionManagerCallback-20119-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2479984 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2479996 INFO  (zkConnectionManagerCallback-20125-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2479997 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2480210 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2480215 WARN  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480224 WARN  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@7667aa3f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480236 WARN  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480253 WARN  (jetty-launcher-20117-thread-3) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@103e596d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480255 INFO  (jetty-launcher-20117-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44030/solr
   [junit4]   2> 2480285 INFO  (zkConnectionManagerCallback-20135-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480302 INFO  (zkConnectionManagerCallback-20137-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480396 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:44787_solr
   [junit4]   2> 2480397 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.Overseer Overseer 
(id=73056199839842312-127.0.0.1:44787_solr-n_0000000000) starting
   [junit4]   2> 2480423 INFO  (zkConnectionManagerCallback-20144-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480425 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:44030/solr ready
   [junit4]   2> 2480433 INFO  
(OverseerStateUpdate-73056199839842312-127.0.0.1:44787_solr-n_0000000000) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:44787_solr
   [junit4]   2> 2480441 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:44787_solr
   [junit4]   2> 2480454 INFO  (zkCallback-20143-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2480469 INFO  (zkCallback-20136-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2480502 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2480573 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44787.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480599 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44787.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480601 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44787.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480603 INFO  (jetty-launcher-20117-thread-3) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node3/.
   [junit4]   2> 2480629 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2480629 WARN  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480651 WARN  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@39b2959a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480655 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2480655 WARN  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480655 WARN  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480656 WARN  (jetty-launcher-20117-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@409cd101[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480657 INFO  (jetty-launcher-20117-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44030/solr
   [junit4]   2> 2480658 WARN  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@4730bf6c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480666 WARN  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480674 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2480674 WARN  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480675 INFO  (zkConnectionManagerCallback-20152-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480676 WARN  (jetty-launcher-20117-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@49a3d6e8[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480676 WARN  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@4575fa4f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480677 INFO  (jetty-launcher-20117-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44030/solr
   [junit4]   2> 2480679 WARN  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2480681 WARN  (jetty-launcher-20117-thread-4) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@5b5df49f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2480682 INFO  (jetty-launcher-20117-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44030/solr
   [junit4]   2> 2480684 INFO  (zkConnectionManagerCallback-20165-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480685 INFO  (zkConnectionManagerCallback-20162-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480686 INFO  (zkConnectionManagerCallback-20159-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480688 INFO  (zkConnectionManagerCallback-20167-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480697 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2480701 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:38032_solr as DOWN
   [junit4]   2> 2480702 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 2480702 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38032_solr
   [junit4]   2> 2480704 INFO  (zkCallback-20136-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2480710 INFO  (zkCallback-20143-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2480712 INFO  (zkConnectionManagerCallback-20171-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480714 INFO  (zkCallback-20166-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2480716 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2480717 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2480721 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:46486_solr as DOWN
   [junit4]   2> 2480722 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 2480722 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46486_solr
   [junit4]   2> 2480723 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:39051_solr as DOWN
   [junit4]   2> 2480723 INFO  (zkCallback-20143-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2480724 INFO  (zkCallback-20136-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2480725 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 2480725 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39051_solr
   [junit4]   2> 2480725 INFO  (zkCallback-20166-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2480728 INFO  (zkCallback-20170-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2480732 INFO  (zkCallback-20143-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2480732 INFO  (zkCallback-20166-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2480732 INFO  (zkCallback-20161-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2480732 INFO  (zkCallback-20136-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2480732 INFO  (zkCallback-20170-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2480746 INFO  (zkConnectionManagerCallback-20180-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480747 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 2480748 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:44030/solr ready
   [junit4]   2> 2480752 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2480767 INFO  (zkConnectionManagerCallback-20185-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480768 INFO  (zkConnectionManagerCallback-20191-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2480769 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 2480770 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:44030/solr ready
   [junit4]   2> 2480770 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2480771 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 2480773 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:44030/solr ready
   [junit4]   2> 2480779 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2480835 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46486.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480851 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39051.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480862 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38032.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480867 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46486.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480868 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46486.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480871 INFO  (jetty-launcher-20117-thread-1) 
[n:127.0.0.1:46486_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/.
   [junit4]   2> 2480881 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39051.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480882 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39051.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480883 INFO  (jetty-launcher-20117-thread-2) 
[n:127.0.0.1:39051_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node2/.
   [junit4]   2> 2480884 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38032.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480884 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38032.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2480886 INFO  (jetty-launcher-20117-thread-4) 
[n:127.0.0.1:38032_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node4/.
   [junit4]   2> 2481062 INFO  (zkConnectionManagerCallback-20198-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2481064 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2481066 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:44030/solr ready
   [junit4]   2> 2481067 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.u.p.CategoryRoutedAliasUpdateProcessorTest SolrClient: 
org.apache.solr.client.solrj.impl.CloudSolrClient@56659278
   [junit4]   2> 2481067 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.u.p.CategoryRoutedAliasUpdateProcessorTest ClusterStateProvider 
127.0.0.1:44030/solr
   [junit4]   2> 2481069 INFO  (zkConnectionManagerCallback-20203-thread-1) [   
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2481070 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2481072 INFO  
(TEST-CategoryRoutedAliasUpdateProcessorTest.testMustMatch-seed#[CE854C3303CD16])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:44030/solr ready
   [junit4]   2> 2481095 INFO  (qtp1305846248-51880) [n:127.0.0.1:44787_solr    
] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params 
baseConfigSet=_default&name=testMustMatch&action=CREATE&wt=javabin&version=2 
   [junit4]   2> 2481102 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.OverseerConfigSetMessageHandler 
OverseerConfigSetMessageHandler.processMessage : create , {
   [junit4]   2>   "name":"testMustMatch",
   [junit4]   2>   "baseConfigSet":"_default",
   [junit4]   2>   "immutable":"false",
   [junit4]   2>   "operation":"configsets:create"}
   [junit4]   2> 2481104 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to /configs/testMustMatch/managed-schema
   [junit4]   2> 2481107 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to /configs/testMustMatch/protwords.txt
   [junit4]   2> 2481109 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to /configs/testMustMatch/solrconfig.xml
   [junit4]   2> 2481112 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to /configs/testMustMatch/synonyms.txt
   [junit4]   2> 2481113 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to /configs/testMustMatch/stopwords.txt
   [junit4]   2> 2481116 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_it.txt to 
/configs/testMustMatch/lang/contractions_it.txt
   [junit4]   2> 2481118 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ca.txt to 
/configs/testMustMatch/lang/contractions_ca.txt
   [junit4]   2> 2481119 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stemdict_nl.txt to 
/configs/testMustMatch/lang/stemdict_nl.txt
   [junit4]   2> 2481121 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_no.txt to 
/configs/testMustMatch/lang/stopwords_no.txt
   [junit4]   2> 2481123 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hy.txt to 
/configs/testMustMatch/lang/stopwords_hy.txt
   [junit4]   2> 2481125 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_id.txt to 
/configs/testMustMatch/lang/stopwords_id.txt
   [junit4]   2> 2481127 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hi.txt to 
/configs/testMustMatch/lang/stopwords_hi.txt
   [junit4]   2> 2481129 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ga.txt to 
/configs/testMustMatch/lang/stopwords_ga.txt
   [junit4]   2> 2481131 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_da.txt to 
/configs/testMustMatch/lang/stopwords_da.txt
   [junit4]   2> 2481133 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ja.txt to 
/configs/testMustMatch/lang/stopwords_ja.txt
   [junit4]   2> 2481135 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_pt.txt to 
/configs/testMustMatch/lang/stopwords_pt.txt
   [junit4]   2> 2481137 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hu.txt to 
/configs/testMustMatch/lang/stopwords_hu.txt
   [junit4]   2> 2481139 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_el.txt to 
/configs/testMustMatch/lang/stopwords_el.txt
   [junit4]   2> 2481141 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ru.txt to 
/configs/testMustMatch/lang/stopwords_ru.txt
   [junit4]   2> 2481143 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_tr.txt to 
/configs/testMustMatch/lang/stopwords_tr.txt
   [junit4]   2> 2481144 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ar.txt to 
/configs/testMustMatch/lang/stopwords_ar.txt
   [junit4]   2> 2481146 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/userdict_ja.txt to 
/configs/testMustMatch/lang/userdict_ja.txt
   [junit4]   2> 2481148 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_eu.txt to 
/configs/testMustMatch/lang/stopwords_eu.txt
   [junit4]   2> 2481150 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_it.txt to 
/configs/testMustMatch/lang/stopwords_it.txt
   [junit4]   2> 2481152 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_cz.txt to 
/configs/testMustMatch/lang/stopwords_cz.txt
   [junit4]   2> 2481154 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stoptags_ja.txt to 
/configs/testMustMatch/lang/stoptags_ja.txt
   [junit4]   2> 2481156 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_fr.txt to 
/configs/testMustMatch/lang/contractions_fr.txt
   [junit4]   2> 2481158 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_de.txt to 
/configs/testMustMatch/lang/stopwords_de.txt
   [junit4]   2> 2481160 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fa.txt to 
/configs/testMustMatch/lang/stopwords_fa.txt
   [junit4]   2> 2481162 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ga.txt to 
/configs/testMustMatch/lang/contractions_ga.txt
   [junit4]   2> 2481164 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ca.txt to 
/configs/testMustMatch/lang/stopwords_ca.txt
   [junit4]   2> 2481165 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_nl.txt to 
/configs/testMustMatch/lang/stopwords_nl.txt
   [junit4]   2> 2481167 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_bg.txt to 
/configs/testMustMatch/lang/stopwords_bg.txt
   [junit4]   2> 2481170 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_en.txt to 
/configs/testMustMatch/lang/stopwords_en.txt
   [junit4]   2> 2481172 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_es.txt to 
/configs/testMustMatch/lang/stopwords_es.txt
   [junit4]   2> 2481174 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_lv.txt to 
/configs/testMustMatch/lang/stopwords_lv.txt
   [junit4]   2> 2481176 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ro.txt to 
/configs/testMustMatch/lang/stopwords_ro.txt
   [junit4]   2> 2481177 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_th.txt to 
/configs/testMustMatch/lang/stopwords_th.txt
   [junit4]   2> 2481179 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fr.txt to 
/configs/testMustMatch/lang/stopwords_fr.txt
   [junit4]   2> 2481181 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_sv.txt to 
/configs/testMustMatch/lang/stopwords_sv.txt
   [junit4]   2> 2481183 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fi.txt to 
/configs/testMustMatch/lang/stopwords_fi.txt
   [junit4]   2> 2481185 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/hyphenations_ga.txt to 
/configs/testMustMatch/lang/hyphenations_ga.txt
   [junit4]   2> 2481187 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_gl.txt to 
/configs/testMustMatch/lang/stopwords_gl.txt
   [junit4]   2> 2481189 INFO  
(OverseerThreadFactory-13693-thread-1-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/params.json to /configs/testMustMatch/params.json
   [junit4]   2> 2481195 INFO  (qtp1305846248-51880) [n:127.0.0.1:44787_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/configs 
params={baseConfigSet=_default&name=testMustMatch&action=CREATE&wt=javabin&version=2}
 status=0 QTime=102
   [junit4]   2> 2481197 INFO  (qtp1305846248-51879) [n:127.0.0.1:44787_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=testMustMatch&name=testMustMatch&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2481202 INFO  
(OverseerCollectionConfigSetProcessor-73056199839842312-127.0.0.1:44787_solr-n_0000000000)
 [n:127.0.0.1:44787_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> 2481202 INFO  
(OverseerThreadFactory-13693-thread-2-processing-n:127.0.0.1:44787_solr) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testMustMatch
   [junit4]   2> 2481320 INFO  
(OverseerStateUpdate-73056199839842312-127.0.0.1:44787_solr-n_0000000000) 
[n:127.0.0.1:44787_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testMustMatch",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testMustMatch_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:46486/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2481535 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr    
x:testMustMatch_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=testMustMatch&newCollection=true&name=testMustMatch_shard1_replica_n1&action=CREATE&numShards=1&collection=testMustMatch&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2482551 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.RequestParams conf resource params.json loaded . version : 0 
   [junit4]   2> 2482551 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.RequestParams request params refreshed to version 0
   [junit4]   2> 2482551 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/extraction/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/extraction/lib
   [junit4]   2> 2482552 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-cell-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2482552 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/clustering/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/clustering/lib
   [junit4]   2> 2482552 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-clustering-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2482553 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/langid/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/langid/lib
   [junit4]   2> 2482553 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-langid-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2482553 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/velocity/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/velocity/lib
   [junit4]   2> 2482553 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-velocity-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2482554 WARN  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-ltr-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2482563 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 2482604 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.IndexSchema [testMustMatch_shard1_replica_n1] Schema name=default-config
   [junit4]   2> 2482834 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
   [junit4]   2> 2482872 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'testMustMatch_shard1_replica_n1' using 
configuration from collection testMustMatch, trusted=true
   [junit4]   2> 2482873 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46486.solr.core.testMustMatch.shard1.replica_n1' (registry 
'solr.core.testMustMatch.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2482873 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrCore [[testMustMatch_shard1_replica_n1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/./testMustMatch_shard1_replica_n1/data/]
   [junit4]   2> 2482877 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
   [junit4]   2> 2482949 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2482949 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2482952 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; 
   [junit4]   2> 2482952 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2482954 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6d8ee26e[testMustMatch_shard1_replica_n1] main]
   [junit4]   2> 2482955 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/testMustMatch
   [junit4]   2> 2482956 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/testMustMatch
   [junit4]   2> 2482956 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/testMustMatch/managed-schema
   [junit4]   2> 2482956 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 2482957 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2482957 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
   [junit4]   2> 2482959 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2482959 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1629594860940951552
   [junit4]   2> 2482962 INFO  
(searcherExecutor-13719-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.c.QuerySenderListener 
QuerySenderListener sending requests to 
Searcher@6d8ee26e[testMustMatch_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2482962 INFO  
(searcherExecutor-13719-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.c.QuerySenderListener 
QuerySenderListener done.
   [junit4]   2> 2482962 INFO  
(searcherExecutor-13719-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell 
index for spellchecker: default
   [junit4]   2> 2482963 INFO  
(searcherExecutor-13719-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.c.SolrCore 
[testMustMatch_shard1_replica_n1] Registered new searcher 
Searcher@6d8ee26e[testMustMatch_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2482966 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/testMustMatch/terms/shard1 to Terms{values={core_node2=0}, 
version=0}
   [junit4]   2> 2482966 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/testMustMatch/leaders/shard1
   [junit4]   2> 2482970 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2482970 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2482970 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:46486/solr/testMustMatch_shard1_replica_n1/
   [junit4]   2> 2482971 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2482971 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SyncStrategy 
https://127.0.0.1:46486/solr/testMustMatch_shard1_replica_n1/ has no replicas
   [junit4]   2> 2482971 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/testMustMatch/leaders/shard1/leader after winning as 
/collections/testMustMatch/leader_elect/shard1/election/73056199839842319-core_node2-n_0000000000
   [junit4]   2> 2482973 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:46486/solr/testMustMatch_shard1_replica_n1/ shard1
   [junit4]   2> 2483075 INFO  (zkCallback-20170-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testMustMatch/state.json] for collection [testMustMatch] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2483075 INFO  (zkCallback-20170-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testMustMatch/state.json] for collection [testMustMatch] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2483076 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2483079 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testMustMatch&newCollection=true&name=testMustMatch_shard1_replica_n1&action=CREATE&numShards=1&collection=testMustMatch&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1545
   [junit4]   2> 2483086 INFO  (qtp1305846248-51879) [n:127.0.0.1:44787_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> 2483181 INFO  (zkCallback-20136-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testMustMatch/state.json] for collection [testMustMatch] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2483181 INFO  (zkCallback-20170-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testMustMatch/state.json] for collection [testMustMatch] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2483181 INFO  (zkCallback-20170-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testMustMatch/state.json] for collection [testMustMatch] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2483182 INFO  (zkCallback-20170-thread-3) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testMustMatch/state.json] for collection [testMustMatch] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2483183 INFO  (qtp1305846248-51879) [n:127.0.0.1:44787_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collection.configName=testMustMatch&name=testMustMatch&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=1986
   [junit4]   2> 2483212 INFO  
(OverseerCollectionConfigSetProcessor-73056199839842312-127.0.0.1:44787_solr-n_0000000000)
 [n:127.0.0.1:44787_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2483249 INFO  (qtp1420986346-51871) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted 
to ZK [{"set-user-property":{"update.autoCreateFields":false}}, 
{"add-updateprocessor":{
   [junit4]   2>     "name":"tolerant",
   [junit4]   2>     "class":"solr.TolerantUpdateProcessorFactory"}}, 
{"add-updateprocessor":{
   [junit4]   2>     "name":"tracking-testSliceRouting",
   [junit4]   2>     "class":"solr.TrackingUpdateProcessorFactory",
   [junit4]   2>     "group":"testMustMatch"}}, {"add-updateprocessor":{
   [junit4]   2>     "name":"inc",
   [junit4]   2>     
"class":"org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest$IncrementURPFactory",
   [junit4]   2>     "fieldName":"integer_i"}}]
   [junit4]   2> 2483249 INFO  (qtp1420986346-51871) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the 
property overlay to be of version 0 for collection testMustMatch
   [junit4]   2> 2483250 INFO  (Thread-9371) [    ] o.a.s.c.SolrCore config 
update listener called for core testMustMatch_shard1_replica_n1
   [junit4]   2> 2483251 INFO  (Thread-9371) [    ] o.a.s.c.SolrCore core 
reload testMustMatch_shard1_replica_n1
   [junit4]   2> 2483266 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 
secs, maxWait 30
   [junit4]   2> 2483268 INFO  (Thread-9371) [    ] o.a.s.c.RequestParams conf 
resource params.json loaded . version : 0 
   [junit4]   2> 2483268 INFO  (Thread-9371) [    ] o.a.s.c.RequestParams 
request params refreshed to version 0
   [junit4]   2> 2483269 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/extraction/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/extraction/lib
   [junit4]   2> 2483269 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-cell-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2483270 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/clustering/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/clustering/lib
   [junit4]   2> 2483270 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-clustering-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2483270 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/langid/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/langid/lib
   [junit4]   2> 2483271 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-langid-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2483271 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/velocity/lib
 filtered by .*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../contrib/velocity/lib
   [junit4]   2> 2483271 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-velocity-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2483271 WARN  (Thread-9371) [    ] o.a.s.c.SolrConfig Couldn't 
add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
 filtered by solr-ltr-\d.*\.jar to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1/../../../../dist
   [junit4]   2> 2483286 INFO  (Thread-9371) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 2483329 INFO  (Thread-9371) [    ] o.a.s.s.IndexSchema 
[testMustMatch_shard1_replica_n1] Schema name=default-config
   [junit4]   2> 2483377 INFO  (qtp1420986346-51874) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
   [junit4]   2> 2483377 INFO  (qtp1420986346-51874) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.S.Request [testMustMatch_shard1_replica_n1]  webapp=/solr 
path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 2483378 INFO  (SolrConfigHandler-refreshconf) [    ] 
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
   [junit4]   2> 2483378 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:46486/solr/testMustMatch_shard1_replica_n1/ for prop overlay  
 after 1 attempts
   [junit4]   2> 2483378 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 
secs, maxWait 30
   [junit4]   2> 2483482 INFO  (qtp1420986346-51870) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
   [junit4]   2> 2483483 INFO  (qtp1420986346-51870) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.S.Request [testMustMatch_shard1_replica_n1]  webapp=/solr 
path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 2483486 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:46486/solr/testMustMatch_shard1_replica_n1/ for prop overlay  
 after 2 attempts
   [junit4]   2> 2483486 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 
secs, maxWait 30
   [junit4]   2> 2483488 INFO  (SolrConfigHandler-refreshconf) [    ] 
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
   [junit4]   2> 2483596 INFO  (qtp1420986346-51872) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
   [junit4]   2> 2483600 INFO  (qtp1420986346-51872) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.S.Request [testMustMatch_shard1_replica_n1]  webapp=/solr 
path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 
QTime=3
   [junit4]   2> 2483601 INFO  (SolrConfigHandler-refreshconf) [    ] 
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
   [junit4]   2> 2483601 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:46486/solr/testMustMatch_shard1_replica_n1/ for prop overlay  
 after 3 attempts
   [junit4]   2> 2483601 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 
secs, maxWait 30
   [junit4]   2> 2483623 INFO  (Thread-9371) [    ] o.a.s.s.IndexSchema Loaded 
schema default-config/1.6 with uniqueid field id
   [junit4]   2> 2483666 INFO  (Thread-9371) [    ] o.a.s.c.CoreContainer 
Reloading SolrCore 'testMustMatch_shard1_replica_n1' using configuration from 
collection testMustMatch
   [junit4]   2> 2483706 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46486.solr.core.testMustMatch.shard1.replica_n1' (registry 
'solr.core.testMustMatch.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3068b430
   [junit4]   2> 2483706 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.SolrCore [[testMustMatch_shard1_replica_n1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/testMustMatch_shard1_replica_n1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001/tempDir-001/node1/./testMustMatch_shard1_replica_n1/data/]
   [junit4]   2> 2483717 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
   [junit4]   2> 2483719 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
   [junit4]   2> 2483720 INFO  (qtp1420986346-51873) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.c.S.Request [testMustMatch_shard1_replica_n1]  webapp=/solr 
path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 
QTime=3
   [junit4]   2> 2483720 INFO  (SolrConfigHandler-refreshconf) [    ] 
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
   [junit4]   2> 2483721 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:46486/solr/testMustMatch_shard1_replica_n1/ for prop overlay  
 after 4 attempts
   [junit4]   2> 2483721 INFO  
(solrHandlerExecutor-13724-thread-1-processing-n:127.0.0.1:46486_solr 
x:testMustMatch_shard1_replica_n1 c:testMustMatch s:shard1 r:core_node2) 
[n:127.0.0.1:46486_solr c:testMustMatch s:shard1 r:core_node2 
x:testMustMatch_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 
secs, maxWait 30
   [junit4]   2> 2483789 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; 
   [junit4]   2> 2483789 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2483791 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6391a4ea[testMustMatch_shard1_replica_n1] main]
   [junit4]   2> 2483793 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/testMustMatch
   [junit4]   2> 2483794 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/testMustMatch
   [junit4]   2> 2483794 INFO  (Thread-9371) [n:127.0.0.1:46486_solr 
c:testMustMatch s:shard1 r:core_node2 x:testMustMatch_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper w

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

ient.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.directUpdate(BaseCloudSolrClient.java:493)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:964)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:830)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:763)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:177)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
   [junit4]    >        at 
org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest.testSliceRouting(CategoryRoutedAliasUpdateProcessorTest.java:366)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_CE854C3303CD16-001
   [junit4]   2> Apr 01, 2019 7:16:06 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene80, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5544e9fa),
 locale=zh-HK, timezone=Europe/Warsaw
   [junit4]   2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 
1.8.0_191 (64-bit)/cpus=4,threads=1,free=142768992,total=492830720
   [junit4]   2> NOTE: All tests run in this JVM: [TestSort, 
DistributedQueryElevationComponentTest, SolrRequestParserTest, 
ReindexCollectionTest, SolrIndexSplitterTest, TestSolrFieldCacheBean, 
TestFacetMethods, MetricUtilsTest, TestLogWatcher, TestSimComputePlanAction, 
DocExpirationUpdateProcessorFactoryTest, PreAnalyzedFieldTest, 
ChangedSchemaMergeTest, HdfsRestartWhileUpdatingTest, CheckHdfsIndexTest, 
JWTAuthPluginTest, TestCloudJSONFacetJoinDomain, PeerSyncWithLeaderTest, 
TestPolicyCloud, RulesTest, TestRandomCollapseQParserPlugin, TestDynamicURP, 
TestGraphMLResponseWriter, TestCharFilters, SolrMetricsIntegrationTest, 
TestHashPartitioner, TestLMJelinekMercerSimilarityFactory, 
TestSubQueryTransformerDistrib, TestSimLargeCluster, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, DeleteStatusTest, 
LeaderElectionIntegrationTest, TestWriterPerf, TestValueSourceCache, 
TestStressRecovery, TestRTimerTree, HttpPartitionTest, 
URLClassifyProcessorTest, TriggerIntegrationTest, TestCloudDeleteByQuery, 
TestDistributedMap, TestComplexPhraseQParserPlugin, TestSolrCoreSnapshots, 
UpdateRequestProcessorFactoryTest, TestStressCloudBlindAtomicUpdates, 
NodeMutatorTest, TestPKIAuthenticationPlugin, TestJsonFacets, FileUtilsTest, 
TestSchemaNameResource, DebugComponentTest, TestSolrDeletionPolicy2, 
JSONWriterTest, TestOmitPositions, TestHdfsUpdateLog, 
SpellCheckCollatorWithCollapseTest, DocValuesMultiTest, CreateRoutedAliasTest, 
SliceStateTest, DocumentAnalysisRequestHandlerTest, TestFiltering, 
DateFieldTest, DOMUtilTest, TestQueryTypes, TestSlowCompositeReaderWrapper, 
TestReversedWildcardFilterFactory, LeaderFailureAfterFreshStartTest, 
TestFieldCacheSortRandom, BadCopyFieldTest, ExternalFileFieldSortTest, 
ActionThrottleTest, ShardSplitTest, CdcrBidirectionalTest, TimeZoneUtilsTest, 
TestFieldCache, TestCoreContainer, FastVectorHighlighterTest, 
TestRemoteStreaming, TestSimpleTextCodec, LukeRequestHandlerTest, 
TestExpandComponent, StressHdfsTest, TestSimpleTrackingShardHandler, 
SortByFunctionTest, OutputWriterTest, MissingSegmentRecoveryTest, 
BlockJoinFacetSimpleTest, HighlighterTest, SolrJmxReporterCloudTest, 
DistributedFacetSimpleRefinementLongTailTest, 
OverseerCollectionConfigSetProcessorTest, TestPartialUpdateDeduplication, 
SolrCmdDistributorTest, PrimUtilsTest, TestCustomStream, TestSQLHandler, 
TestPrepRecovery, IndexSizeTriggerTest, TestJoin, TestCSVResponseWriter, 
TestRealTimeGet, TestUninvertingReader, TestRequestForwarding, 
NodeAddedTriggerIntegrationTest, OverseerStatusTest, MetricsHistoryHandlerTest, 
V2StandaloneTest, CoreMergeIndexesAdminHandlerTest, SolrCoreMetricManagerTest, 
TestMergePolicyConfig, AnalyticsQueryTest, ScheduledMaintenanceTriggerTest, 
ManagedSchemaRoundRobinCloudTest, TestAnalyzeInfixSuggestions, 
HttpSolrCallGetCoreTest, RollingRestartTest, BitVectorTest, 
CloneFieldUpdateProcessorFactoryTest, TestStressVersions, TestConfigSetsAPI, 
TestInPlaceUpdatesStandalone, TestDocSet, TestSolrConfigHandlerConcurrent, 
TestSolr4Spatial, BasicDistributedZkTest, CdcrReplicationHandlerTest, 
TestHdfsBackupRestoreCore, VMParamsZkACLAndCredentialsProvidersTest, 
ShufflingReplicaListTransformerTest, DistributedFacetExistsSmallTest, 
OpenExchangeRatesOrgProviderTest, TestMultiWordSynonyms, 
ShardRoutingCustomTest, TestLegacyFieldCache, TestCloudRecovery2, 
ReplaceNodeTest, BasicAuthIntegrationTest, TestReload, PeerSyncReplicationTest, 
TestLeaderElectionWithEmptyReplica, TestSSLRandomization, TestSegmentSorting, 
TestStressLiveNodes, TestTolerantUpdateProcessorRandomCloud, TestZkChroot, 
ZkControllerTest, ZkFailoverTest, ZkNodePropsTest, ZkShardTermsTest, 
AssignTest, CollectionReloadTest, CollectionsAPIAsyncDistributedZkTest, 
TestCollectionsAPIViaSolrCloudCluster, AutoAddReplicasPlanActionTest, 
ExecutePlanActionTest, NodeAddedTriggerTest, NodeLostTriggerTest, 
ScheduledTriggerTest, SearchRateTriggerIntegrationTest, 
TestSimDistribStateManager, TestSimExecutePlanAction, TestSimNodeAddedTrigger, 
TestSimNodeLostTrigger, TestSimPolicyCloud, CdcrRequestHandlerTest, 
CdcrVersionReplicationTest, HdfsTlogReplayBufferedWhileIndexingTest, 
TestClusterStateMutator, ZkStateReaderTest, AlternateDirectoryTest, 
BlobRepositoryCloudTest, ConfigureRecoveryStrategyTest, CoreSorterTest, 
DirectoryFactoryTest, ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, 
RequestHandlersTest, SolrCoreCheckLockOnStartupTest, 
TestBackupRepositoryFactory, TestBadConfig, TestConfig, TestConfigOverlay, 
TestConfigSetImmutable, TestQuerySenderNoQuery, TestSolrXml, 
FieldAnalysisRequestHandlerTest, PingRequestHandlerTest, 
TestReplicationHandlerBackup, XmlUpdateRequestHandlerTest, 
XsltUpdateRequestHandlerTest, AutoscalingHistoryHandlerTest, 
DistributedFacetPivotSmallAdvancedTest, DistributedFacetPivotSmallTest, 
DistributedMLTComponentTest, DistributedSuggestComponentTest, 
FacetPivotSmallTest, InfixSuggestersTest, ReplicaListTransformerTest, 
ResponseLogComponentTest, SpellCheckComponentTest, SuggestComponentTest, 
TermVectorComponentTest, TestPivotHelperCode, TestExportWriter, 
RandomizedTaggerTest, TaggerTest, TestLegacyField, TestLegacyNumericUtils, 
SolrJmxReporterTest, SolrShardReporterTest, TestRetrieveFieldsOptimizer, 
TestChildDocTransformerHierarchy, TestManagedResourceStorage, 
TestDynamicFieldCollectionResource, TestFieldCollectionResource, 
TestSchemaResource, TestSchemaVersionResource, TestUniqueKeyFieldResource, 
PrimitiveFieldTypeTest, RequiredFieldsTest, SchemaApiFailureTest, 
TestCloudManagedSchema, TestCollationField, TestCollationFieldDocValues, 
TestHalfAndHalfDocValues, TestManagedSchema, TestSchemaManager, 
TestSchemalessBufferedUpdates, TestSortableTextField, TestUseDocValuesAsStored, 
CursorMarkTest, LargeFieldTest, QueryParsingTest, RankQueryTest, 
SignificantTermsQParserPluginTest, SortSpecParsingTest, 
TestAddFieldRealTimeGet, TestElisionMultitermQuery, 
TestGraphTermsQParserPlugin, TestIndexSearcher, TestNoOpRegenerator, 
TestPayloadCheckQParserPlugin, TestSearcherReuse, TestMinMaxOnMultiValuedField, 
GraphQueryTest, TestNestedDocsSort, TestScoreJoinQPNoScore, 
CloudMLTQParserTest, TestSolrCloudWithHadoopAuthPlugin, SuggesterTSTTest, 
TestFileDictionaryLookup, TestHighFrequencyDictionaryFactory, 
BlockDirectoryTest, DocumentBuilderTest, MaxSizeAutoCommitTest, 
PeerSyncWithBufferUpdatesTest, TestIndexingPerformance, 
TestNestedUpdateProcessor, TestUpdate, TransactionLogTest, UpdateLogTest, 
VersionInfoTest, AtomicUpdateProcessorFactoryTest, AtomicUpdatesTest, 
CategoryRoutedAliasUpdateProcessorTest]
   [junit4] Completed [846/846 (1!)] on J2 in 204.75s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 1 lines...]
   [junit4] JVM J2: stdout was not empty, see: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/temp/junit4-J2-20190401_063121_9554845862946667484920.sysout
   [junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] Dumping heap to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/heapdumps/java_pid14447.hprof
 ...
   [junit4] Heap dump file created [751964620 bytes in 8.597 secs]
   [junit4] <<< JVM J2: EOF ----

[...truncated 8950 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:633: The 
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:585: 
Some of the tests produced a heap dump, but did not fail. Maybe a suppressed 
OutOfMemoryError? Dumps created:
* java_pid14447.hprof

Total time: 99 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[Fast Archiver] No artifacts from Lucene-Solr-Tests-master #3238 to compare, so 
performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to