You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2019/04/01 07:34:35 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 3239 - Failure
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