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/27 02:51:37 UTC
[JENKINS] Lucene-Solr-Tests-8.x - Build # 170 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-8.x/170/
2 tests failed.
FAILED: org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest.testSliceRouting
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([14F3A3B69EFF36C1]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([14F3A3B69EFF36C1]:0)
Build Log:
[...truncated 15865 lines...]
[junit4] Suite: org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest
[junit4] 2> 1278348 INFO (SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[14F3A3B69EFF36C1]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/init-core-data-001
[junit4] 2> 1278349 WARN (SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[14F3A3B69EFF36C1]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=175 numCloses=175
[junit4] 2> 1278349 INFO (SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[14F3A3B69EFF36C1]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1278351 INFO (SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[14F3A3B69EFF36C1]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1278354 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1278354 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001
[junit4] 2> 1278355 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1278355 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1278355 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1278455 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.ZkTestServer start zk server on port:36122
[junit4] 2> 1278455 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36122
[junit4] 2> 1278455 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36122
[junit4] 2> 1278479 INFO (zkConnectionManagerCallback-9486-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278489 INFO (zkConnectionManagerCallback-9488-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278491 INFO (zkConnectionManagerCallback-9490-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278493 WARN (jetty-launcher-9491-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1278494 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1278494 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1278494 INFO (jetty-launcher-9491-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> 1278495 WARN (jetty-launcher-9491-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1278495 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1278495 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1278495 WARN (jetty-launcher-9491-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1278495 INFO (jetty-launcher-9491-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> 1278495 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1278495 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1278495 INFO (jetty-launcher-9491-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1278495 INFO (jetty-launcher-9491-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1278496 INFO (jetty-launcher-9491-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> 1278496 WARN (jetty-launcher-9491-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1278496 INFO (jetty-launcher-9491-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1278496 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1278496 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1278496 INFO (jetty-launcher-9491-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> 1278498 INFO (jetty-launcher-9491-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1278498 INFO (jetty-launcher-9491-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1278498 INFO (jetty-launcher-9491-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1278498 INFO (jetty-launcher-9491-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3891170a{/solr,null,AVAILABLE}
[junit4] 2> 1278500 INFO (jetty-launcher-9491-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1278500 INFO (jetty-launcher-9491-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1278500 INFO (jetty-launcher-9491-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1278500 INFO (jetty-launcher-9491-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@70f4845f{/solr,null,AVAILABLE}
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f4a243c{/solr,null,AVAILABLE}
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1f7bd77d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42644}
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.e.j.s.Server Started @1278691ms
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42644}
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3080046d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44146}
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-1) [ ] o.e.j.s.Server Started @1278691ms
[junit4] 2> 1278501 ERROR (jetty-launcher-9491-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44146}
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T00:37:15.302Z
[junit4] 2> 1278501 ERROR (jetty-launcher-9491-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1278501 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1278502 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1278502 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1278502 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1278502 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T00:37:15.303Z
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5708b816{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:38739}
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.e.j.s.Server Started @1278692ms
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38739}
[junit4] 2> 1278503 ERROR (jetty-launcher-9491-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1278503 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T00:37:15.304Z
[junit4] 2> 1278508 INFO (jetty-launcher-9491-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1278508 INFO (jetty-launcher-9491-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1278508 INFO (jetty-launcher-9491-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1278508 INFO (jetty-launcher-9491-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c0f0282{/solr,null,AVAILABLE}
[junit4] 2> 1278510 INFO (zkConnectionManagerCallback-9497-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278510 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@32bd99e3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44492}
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.e.j.s.Server Started @1278709ms
[junit4] 2> 1278519 INFO (zkConnectionManagerCallback-9495-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44492}
[junit4] 2> 1278519 INFO (zkConnectionManagerCallback-9493-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278519 ERROR (jetty-launcher-9491-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1278519 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T00:37:15.320Z
[junit4] 2> 1278520 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1278523 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1278563 INFO (zkConnectionManagerCallback-9499-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278563 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1278621 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1278623 WARN (jetty-launcher-9491-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7f64b6e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278627 WARN (jetty-launcher-9491-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@21575742[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278628 INFO (jetty-launcher-9491-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36122/solr
[junit4] 2> 1278636 INFO (zkConnectionManagerCallback-9509-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278639 INFO (zkConnectionManagerCallback-9511-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278668 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1278669 WARN (jetty-launcher-9491-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3686a759[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278674 WARN (jetty-launcher-9491-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5ca8994[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278675 INFO (jetty-launcher-9491-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36122/solr
[junit4] 2> 1278695 INFO (zkConnectionManagerCallback-9519-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278708 INFO (zkConnectionManagerCallback-9521-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278735 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38739_solr
[junit4] 2> 1278737 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.c.Overseer Overseer (id=73201864842280970-127.0.0.1:38739_solr-n_0000000000) starting
[junit4] 2> 1278742 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1278744 WARN (jetty-launcher-9491-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3c38f650[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278749 INFO (zkConnectionManagerCallback-9530-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278749 WARN (jetty-launcher-9491-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@8337147[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278750 INFO (jetty-launcher-9491-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36122/solr
[junit4] 2> 1278752 INFO (zkConnectionManagerCallback-9534-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278753 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36122/solr ready
[junit4] 2> 1278755 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38739_solr
[junit4] 2> 1278758 INFO (zkConnectionManagerCallback-9536-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278759 INFO (zkCallback-9529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1278759 INFO (OverseerStateUpdate-73201864842280970-127.0.0.1:38739_solr-n_0000000000) [n:127.0.0.1:38739_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38739_solr
[junit4] 2> 1278768 INFO (OverseerStateUpdate-73201864842280970-127.0.0.1:38739_solr-n_0000000000) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1278780 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1278786 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1278789 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:44492_solr as DOWN
[junit4] 2> 1278791 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1278791 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44492_solr
[junit4] 2> 1278791 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1278794 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42644_solr as DOWN
[junit4] 2> 1278794 INFO (zkCallback-9529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1278795 INFO (zkCallback-9520-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1278795 INFO (zkCallback-9510-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1278796 INFO (zkCallback-9535-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1278796 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1278796 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42644_solr
[junit4] 2> 1278804 INFO (zkCallback-9520-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1278804 INFO (zkCallback-9529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1278804 INFO (zkCallback-9510-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1278815 INFO (zkCallback-9535-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1278820 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1278824 WARN (jetty-launcher-9491-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@ece5cad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278832 WARN (jetty-launcher-9491-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@32e95e14[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1278834 INFO (jetty-launcher-9491-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36122/solr
[junit4] 2> 1278837 INFO (zkConnectionManagerCallback-9545-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278838 INFO (zkConnectionManagerCallback-9550-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278839 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38739.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278840 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1278841 INFO (zkConnectionManagerCallback-9552-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278842 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36122/solr ready
[junit4] 2> 1278842 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1278848 INFO (zkConnectionManagerCallback-9558-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278850 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1278852 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36122/solr ready
[junit4] 2> 1278852 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1278852 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1278853 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38739.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278853 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38739.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278855 INFO (jetty-launcher-9491-thread-2) [n:127.0.0.1:38739_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/.
[junit4] 2> 1278856 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:44146_solr as DOWN
[junit4] 2> 1278858 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1278858 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44146_solr
[junit4] 2> 1278860 INFO (zkCallback-9520-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1278860 INFO (zkCallback-9510-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1278861 INFO (zkCallback-9529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1278861 INFO (zkCallback-9535-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1278861 INFO (zkCallback-9549-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1278862 INFO (zkCallback-9557-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1278868 INFO (zkCallback-9551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1278887 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42644.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278889 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44492.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278890 INFO (zkConnectionManagerCallback-9566-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1278891 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1278893 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36122/solr ready
[junit4] 2> 1278893 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1278909 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42644.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278910 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42644.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278911 INFO (jetty-launcher-9491-thread-4) [n:127.0.0.1:42644_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node3/.
[junit4] 2> 1278914 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44492.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278915 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44492.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278916 INFO (jetty-launcher-9491-thread-3) [n:127.0.0.1:44492_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node4/.
[junit4] 2> 1278924 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44146.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278940 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44146.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278940 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44146.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1278942 INFO (jetty-launcher-9491-thread-1) [n:127.0.0.1:44146_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node1/.
[junit4] 2> 1279116 INFO (zkConnectionManagerCallback-9572-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1279118 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1279119 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36122/solr ready
[junit4] 2> 1279120 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.u.p.CategoryRoutedAliasUpdateProcessorTest SolrClient: org.apache.solr.client.solrj.impl.CloudSolrClient@7fa7eb35
[junit4] 2> 1279120 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.u.p.CategoryRoutedAliasUpdateProcessorTest ClusterStateProvider 127.0.0.1:36122/solr
[junit4] 2> 1279123 INFO (zkConnectionManagerCallback-9577-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1279125 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1279126 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.test-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36122/solr ready
[junit4] 2> 1279128 INFO (qtp284259657-25635) [n:127.0.0.1:38739_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=_default&name=test&action=CREATE&wt=javabin&version=2
[junit4] 2> 1279131 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , {
[junit4] 2> "name":"test",
[junit4] 2> "baseConfigSet":"_default",
[junit4] 2> "immutable":"false",
[junit4] 2> "operation":"configsets:create"}
[junit4] 2> 1279133 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/test/managed-schema
[junit4] 2> 1279135 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/test/protwords.txt
[junit4] 2> 1279137 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/test/solrconfig.xml
[junit4] 2> 1279139 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/test/synonyms.txt
[junit4] 2> 1279141 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/test/stopwords.txt
[junit4] 2> 1279144 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/test/lang/contractions_it.txt
[junit4] 2> 1279146 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/test/lang/contractions_ca.txt
[junit4] 2> 1279148 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/test/lang/stemdict_nl.txt
[junit4] 2> 1279150 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/test/lang/stopwords_no.txt
[junit4] 2> 1279152 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/test/lang/stopwords_hy.txt
[junit4] 2> 1279154 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/test/lang/stopwords_id.txt
[junit4] 2> 1279156 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/test/lang/stopwords_hi.txt
[junit4] 2> 1279158 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/test/lang/stopwords_ga.txt
[junit4] 2> 1279160 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/test/lang/stopwords_da.txt
[junit4] 2> 1279162 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/test/lang/stopwords_ja.txt
[junit4] 2> 1279163 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/test/lang/stopwords_pt.txt
[junit4] 2> 1279166 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/test/lang/stopwords_hu.txt
[junit4] 2> 1279168 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/test/lang/stopwords_el.txt
[junit4] 2> 1279169 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/test/lang/stopwords_ru.txt
[junit4] 2> 1279171 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/test/lang/stopwords_tr.txt
[junit4] 2> 1279173 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/test/lang/stopwords_ar.txt
[junit4] 2> 1279175 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/test/lang/userdict_ja.txt
[junit4] 2> 1279177 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/test/lang/stopwords_eu.txt
[junit4] 2> 1279179 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/test/lang/stopwords_it.txt
[junit4] 2> 1279181 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/test/lang/stopwords_cz.txt
[junit4] 2> 1279183 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/test/lang/stoptags_ja.txt
[junit4] 2> 1279185 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/test/lang/contractions_fr.txt
[junit4] 2> 1279187 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/test/lang/stopwords_de.txt
[junit4] 2> 1279188 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/test/lang/stopwords_fa.txt
[junit4] 2> 1279190 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/test/lang/contractions_ga.txt
[junit4] 2> 1279192 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/test/lang/stopwords_ca.txt
[junit4] 2> 1279194 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/test/lang/stopwords_nl.txt
[junit4] 2> 1279202 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/test/lang/stopwords_bg.txt
[junit4] 2> 1279204 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/test/lang/stopwords_en.txt
[junit4] 2> 1279206 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/test/lang/stopwords_es.txt
[junit4] 2> 1279209 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/test/lang/stopwords_lv.txt
[junit4] 2> 1279211 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/test/lang/stopwords_ro.txt
[junit4] 2> 1279213 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/test/lang/stopwords_th.txt
[junit4] 2> 1279215 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/test/lang/stopwords_fr.txt
[junit4] 2> 1279217 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/test/lang/stopwords_sv.txt
[junit4] 2> 1279219 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/test/lang/stopwords_fi.txt
[junit4] 2> 1279221 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/test/lang/hyphenations_ga.txt
[junit4] 2> 1279222 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/test/lang/stopwords_gl.txt
[junit4] 2> 1279224 INFO (OverseerThreadFactory-7922-thread-1-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/test/params.json
[junit4] 2> 1279227 INFO (qtp284259657-25635) [n:127.0.0.1:38739_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/configs params={baseConfigSet=_default&name=test&action=CREATE&wt=javabin&version=2} status=0 QTime=99
[junit4] 2> 1279228 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=test&name=test&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1279233 INFO (OverseerThreadFactory-7922-thread-2-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection test
[junit4] 2> 1279233 INFO (OverseerCollectionConfigSetProcessor-73201864842280970-127.0.0.1:38739_solr-n_0000000000) [n:127.0.0.1:38739_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> 1279345 INFO (OverseerStateUpdate-73201864842280970-127.0.0.1:38739_solr-n_0000000000) [n:127.0.0.1:38739_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38739/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1279548 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=test&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1279548 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1280566 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1280566 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1280567 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 1280567 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1280567 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 1280568 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1280568 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 1280568 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1280568 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 1280569 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1280569 WARN (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1280569 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1280602 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema [test_shard1_replica_n1] Schema name=default-config
[junit4] 2> 1280873 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1280940 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'test_shard1_replica_n1' using configuration from collection test, trusted=true
[junit4] 2> 1280941 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38739.solr.core.test.shard1.replica_n1' (registry 'solr.core.test.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1280941 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore [[test_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/./test_shard1_replica_n1/data/]
[junit4] 2> 1280947 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1281043 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1281043 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1281046 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1281046 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1281048 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@54e862da[test_shard1_replica_n1] main]
[junit4] 2> 1281050 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/test
[junit4] 2> 1281051 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/test
[junit4] 2> 1281051 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/test/managed-schema
[junit4] 2> 1281052 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1281052 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1281052 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_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> 1281054 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1281054 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1631925502324244480
[junit4] 2> 1281058 INFO (searcherExecutor-7942-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@54e862da[test_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1281059 INFO (searcherExecutor-7942-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1281059 INFO (searcherExecutor-7942-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1281062 INFO (searcherExecutor-7942-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore [test_shard1_replica_n1] Registered new searcher Searcher@54e862da[test_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1281068 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/test/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1281068 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/test/leaders/shard1
[junit4] 2> 1281073 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1281073 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1281073 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:38739/solr/test_shard1_replica_n1/
[junit4] 2> 1281074 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1281074 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:38739/solr/test_shard1_replica_n1/ has no replicas
[junit4] 2> 1281074 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/test/leaders/shard1/leader after winning as /collections/test/leader_elect/shard1/election/73201864842280970-core_node2-n_0000000000
[junit4] 2> 1281076 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38739/solr/test_shard1_replica_n1/ shard1
[junit4] 2> 1281178 INFO (zkCallback-9520-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for collection [test] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1281178 INFO (zkCallback-9520-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for collection [test] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1281179 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1281182 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=test&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1634
[junit4] 2> 1281187 INFO (qtp284259657-25634) [n:127.0.0.1:38739_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> 1281236 INFO (OverseerCollectionConfigSetProcessor-73201864842280970-127.0.0.1:38739_solr-n_0000000000) [n:127.0.0.1:38739_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> 1281281 INFO (zkCallback-9520-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for collection [test] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1281281 INFO (zkCallback-9520-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for collection [test] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1281281 INFO (zkCallback-9520-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/test/state.json] for collection [test] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1281282 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=test&name=test&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2053
[junit4] 2> 1281306 INFO (qtp284259657-25632) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_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":"test"}}, {"add-updateprocessor":{
[junit4] 2> "name":"inc",
[junit4] 2> "class":"org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest$IncrementURPFactory",
[junit4] 2> "fieldName":"integer_i"}}]
[junit4] 2> 1281307 INFO (qtp284259657-25632) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_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 test
[junit4] 2> 1281307 INFO (Thread-3927) [ ] o.a.s.c.SolrCore config update listener called for core test_shard1_replica_n1
[junit4] 2> 1281308 INFO (Thread-3927) [ ] o.a.s.c.SolrCore core reload test_shard1_replica_n1
[junit4] 2> 1281309 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1281320 INFO (Thread-3927) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1281320 INFO (Thread-3927) [ ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1281321 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 1281321 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1281321 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 1281321 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1281321 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 1281321 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1281322 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 1281322 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1281322 WARN (Thread-3927) [ ] o.a.s.c.SolrConfig Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1/../../../../dist
[junit4] 2> 1281342 INFO (Thread-3927) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1281374 INFO (Thread-3927) [ ] o.a.s.s.IndexSchema [test_shard1_replica_n1] Schema name=default-config
[junit4] 2> 1281410 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 1281411 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request [test_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1281411 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 1281411 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:38739/solr/test_shard1_replica_n1/ for prop overlay after 1 attempts
[junit4] 2> 1281411 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1281512 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 1281512 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request [test_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1281513 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 1281513 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:38739/solr/test_shard1_replica_n1/ for prop overlay after 2 attempts
[junit4] 2> 1281513 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1281571 INFO (Thread-3927) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1281614 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 1281614 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request [test_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1281614 INFO (Thread-3927) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'test_shard1_replica_n1' using configuration from collection test
[junit4] 2> 1281614 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 1281615 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:38739/solr/test_shard1_replica_n1/ for prop overlay after 3 attempts
[junit4] 2> 1281615 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1281655 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38739.solr.core.test.shard1.replica_n1' (registry 'solr.core.test.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f058f00
[junit4] 2> 1281655 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore [[test_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/test_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001/tempDir-001/node2/./test_shard1_replica_n1/data/]
[junit4] 2> 1281659 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1281716 INFO (qtp284259657-25635) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 1281716 INFO (qtp284259657-25635) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request [test_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1281716 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 1281717 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:38739/solr/test_shard1_replica_n1/ for prop overlay after 4 attempts
[junit4] 2> 1281717 INFO (solrHandlerExecutor-7947-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1281729 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1281729 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1281731 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e7735d9[test_shard1_replica_n1] main]
[junit4] 2> 1281733 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/test
[junit4] 2> 1281733 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/test
[junit4] 2> 1281733 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/test/managed-schema
[junit4] 2> 1281734 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1281734 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1281734 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_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> 1281735 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1281736 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1631925503039373312
[junit4] 2> 1281739 INFO (searcherExecutor-7948-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@1e7735d9[test_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1281739 INFO (searcherExecutor-7948-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1281739 INFO (searcherExecutor-7948-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1281740 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1281748 INFO (searcherExecutor-7948-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore [test_shard1_replica_n1] Registered new searcher Searcher@1e7735d9[test_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1281748 INFO (Thread-3927) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@54a01ab2[test_shard1_replica_n1] main]
[junit4] 2> 1281749 INFO (searcherExecutor-7948-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@54a01ab2[test_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1281749 INFO (searcherExecutor-7948-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1281753 INFO (searcherExecutor-7948-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore [test_shard1_replica_n1] Registered new searcher Searcher@54a01ab2[test_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1281818 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler I already have the expected version 0 of config
[junit4] 2> 1281818 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 1281818 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request [test_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1281819 INFO (qtp284259657-25632) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Took 512.0ms to set the property overlay to be of version 0 for collection test
[junit4] 2> 1281819 INFO (qtp284259657-25632) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore [test_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@179b2750
[junit4] 2> 1281819 INFO (qtp284259657-25632) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.test.shard1.replica_n1, tag=179b2750
[junit4] 2> 1281819 INFO (qtp284259657-25632) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@31c5c679: rootName = solr_38739, domain = solr.core.test.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.test.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@28e0facd
[junit4] 2> 1281831 INFO (qtp284259657-25632) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.test.shard1.leader, tag=179b2750
[junit4] 2> 1281836 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.ZkController Persisted config data to node /configs/test/params.json
[junit4] 2> 1281837 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property params to be of version 1 for collection test
[junit4] 2> 1281838 INFO (Thread-3928) [ ] o.a.s.c.SolrCore config update listener called for core test_shard1_replica_n1
[junit4] 2> 1281839 INFO (Thread-3928) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 1
[junit4] 2> 1281839 INFO (Thread-3928) [ ] o.a.s.c.RequestParams request params refreshed to version 1
[junit4] 2> 1281840 INFO (solrHandlerExecutor-7952-thread-1-processing-n:127.0.0.1:38739_solr x:test_shard1_replica_n1 c:test s:shard1 r:core_node2) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1281942 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler I already have the expected version 1 of params
[junit4] 2> 1281942 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 1281942 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.c.S.Request [test_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={params=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1281943 INFO (qtp284259657-25637) [n:127.0.0.1:38739_solr c:test s:shard1 r:core_node2 x:test_shard1_replica_n1] o.a.s.h.SolrConfigHandler Took 105.0ms to set the property params to be of version 1 for collection test
[junit4] 2> 1281944 INFO (qtp284259657-25633) [n:127.0.0.1:38739_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=test&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1281947 INFO (OverseerThreadFactory-7922-thread-3-processing-n:127.0.0.1:38739_solr) [n:127.0.0.1:38739_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 1281948 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.test.shard1.replica_n1, tag=null
[junit4] 2> 1281948 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c69333b: rootName = solr_38739, domain = solr.core.test.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.test.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@28e0facd
[junit4] 2> 1281969 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.c.SolrCore [test_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@62b5810f
[junit4] 2> 1281969 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.test.shard1.replica_n1, tag=62b5810f
[junit4] 2> 1281969 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.test.shard1.leader, tag=62b5810f
[junit4] 2> 1281972 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/test/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 1281973 INFO (qtp284259657-25634) [n:127.0.0.1:38739_solr x:test_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores par
[...truncated too long message...]
) ~[?:1.8.0_191]
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) ~[?:1.8.0_191]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:504) ~[zookeeper-3.4.14.jar:3.4.14-4c25d480e66aadd371de8bd2fd8da255ac140bcf]
[junit4] 2> 8488755 ERROR (zkConnectionManagerCallback-10117-thread-1-EventThread) [ ] o.a.z.ClientCnxn Event thread exiting due to interruption
[junit4] 2> => java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014)
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014) ~[?:1.8.0_191]
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048) ~[?:1.8.0_191]
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) ~[?:1.8.0_191]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:504) ~[zookeeper-3.4.14.jar:3.4.14-4c25d480e66aadd371de8bd2fd8da255ac140bcf]
[junit4] 2> 8488768 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.testSliceRouting-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 8488771 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x10410b66dcb001e: null
[junit4] 2> 8488771 INFO (jetty-closer-10265-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7c1b522a{/solr,null,UNAVAILABLE}
[junit4] 2> 8488771 INFO (jetty-closer-10265-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 8488772 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x10410b66dcb001c: null
[junit4] 2> 8488776 INFO (jetty-closer-10265-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6e25fa4f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 8488781 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.testSliceRouting-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43883
[junit4] 2> 8488781 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.testSliceRouting-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43883
[junit4] 2> 8488783 INFO (TEST-CategoryRoutedAliasUpdateProcessorTest.testSliceRouting-seed#[14F3A3B69EFF36C1]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSliceRouting
[junit4] 2> 8488795 INFO (SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[14F3A3B69EFF36C1]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 8488795 INFO (SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[14F3A3B69EFF36C1]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43883
[junit4] 2> 8488795 INFO (SUITE-CategoryRoutedAliasUpdateProcessorTest-seed#[14F3A3B69EFF36C1]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43883
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.processor.CategoryRoutedAliasUpdateProcessorTest_14F3A3B69EFF36C1-001
[junit4] 2> Apr 27, 2019 2:37:28 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=27573, name=jetty-closer-10265-thread-4, state=TIMED_WAITING, group=TGRP-CategoryRoutedAliasUpdateProcessorTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
[junit4] 2> at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
[junit4] 2> at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=1010, maxMBSortInHeap=6.785869483793773, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4755989), locale=es-CR, timezone=America/Argentina/Salta
[junit4] 2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=2,free=109337368,total=477626368
[junit4] 2> NOTE: All tests run in this JVM: [TestConfigOverlay, TestLRUStatsCache, TestRebalanceLeaders, TestApiFramework, TestBinaryField, SpatialHeatmapFacetsTest, ReturnFieldsTest, TestLazyCores, QueryElevationComponentTest, TestCloudRecovery, SyncSliceTest, TestFreeTextSuggestions, TestSolrConfigHandlerCloud, TestJsonFacetsWithNestedObjects, DateMathParserTest, SuggestComponentTest, PeerSyncWithIndexFingerprintCachingTest, TestSchemaManager, MBeansHandlerTest, AutoAddReplicasIntegrationTest, InfoHandlerTest, SolrGraphiteReporterTest, SpellCheckComponentTest, PropertiesRequestHandlerTest, TestUniqueKeyFieldResource, TestConfigSets, TestLMDirichletSimilarityFactory, UnloadDistributedZkTest, TestSafeXMLParsing, RootFieldTest, TestUpdate, TestCoreAdminApis, NumericFieldsTest, ClusterStateUpdateTest, WordBreakSolrSpellCheckerTest, SampleTest, TestOnReconnectListenerSupport, MissingSegmentRecoveryTest, DeleteReplicaTest, CacheHeaderTest, TestExtendedDismaxParser, TestJettySolrRunner, OverriddenZkACLAndCredentialsProvidersTest, TestEmbeddedSolrServerAdminHandler, TestBadConfig, TaggingAttributeTest, V2ApiIntegrationTest, TestHalfAndHalfDocValues, AnalysisAfterCoreReloadTest, HighlighterConfigTest, TestBinaryResponseWriter, TestNumericTerms32, BasicAuthIntegrationTest, TestCloudConsistency, TestSimGenericDistributedQueue, TestTestInjection, TestInfoStreamLogging, HdfsWriteToMultipleCollectionsTest, CSVRequestHandlerTest, TestSchemaSimilarityResource, TestUseDocValuesAsStored, CollectionsAPIDistributedZkTest, TermsComponentTest, TestMacros, TestSegmentSorting, DistributedUpdateProcessorTest, TestSolrCloudWithSecureImpersonation, SolrSlf4jReporterTest, AddReplicaTest, NumberUtilsTest, ExplicitHLLTest, DistributedFacetPivotSmallTest, ReplaceNodeNoTargetTest, TestXmlQParserPlugin, SpellPossibilityIteratorTest, DistributedSpellCheckComponentTest, RuleEngineTest, TestPHPSerializedResponseWriter, TestUnInvertedFieldException, TestClusterProperties, RandomizedTaggerTest, BasicZkTest, TestCloudPhrasesIdentificationComponent, TestInPlaceUpdateWithRouteField, HttpPartitionWithTlogReplicasTest, HdfsRecoveryZkTest, MoveReplicaTest, ConnectionManagerTest, QueryEqualityTest, DistributedQueryComponentCustomSortTest, RequestHandlersTest, CleanupOldIndexTest, DocValuesMissingTest, TestChildDocTransformer, ZkControllerTest, TestSolrDeletionPolicy1, ParsingFieldUpdateProcessorsTest, SolrCloudExampleTest, TestRestoreCore, HealthCheckHandlerTest, TestDistributedMissingSort, TestSolrCoreProperties, TestCloudSchemaless, TestInPlaceUpdatesStandalone, SimplePostToolTest, TestQuerySenderListener, TestTrieFacet, RecoveryZkTest, SolrRrdBackendFactoryTest, TestSolrQueryParser, HttpTriggerListenerTest, DistribCursorPagingTest, MaxSizeAutoCommitTest, DistributedDebugComponentTest, TestInitQParser, TestFieldTypeResource, TestMaxTokenLenTokenizer, TestDocBasedVersionConstraints, TestRTGBase, CategoryRoutedAliasUpdateProcessorTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CategoryRoutedAliasUpdateProcessorTest -Dtests.seed=14F3A3B69EFF36C1 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-CR -Dtests.timezone=America/Argentina/Salta -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | CategoryRoutedAliasUpdateProcessorTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([14F3A3B69EFF36C1]:0)
[junit4] Completed [836/855 (1!)] on J0 in 7212.93s, 6 tests, 2 errors <<< FAILURES!
[...truncated 69 lines...]
[junit4] JVM J0: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/temp/junit4-J0-20190427_001556_52667385788894574198.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/heapdumps/java_pid29487.hprof ...
[junit4] Heap dump file created [701058200 bytes in 6.195 secs]
[junit4] <<< JVM J0: EOF ----
[...truncated 8948 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/build.xml:634: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/build.xml:586: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created:
* java_pid29487.hprof
Total time: 197 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[Fast Archiver] No artifacts from Lucene-Solr-Tests-8.x #169 to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any