You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/09/16 19:22:29 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 330 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/330/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
Error Message:
took over 10 seconds after collection creation to update aliases
Stack Trace:
java.lang.AssertionError: took over 10 seconds after collection creation to update aliases
at __randomizedtesting.SeedInfo.seed([EF0F862228C0CE96:D6F7C79168E182FD]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest.waitColAndAlias(RoutedAliasUpdateProcessorTest.java:77)
at org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testTimeCat(DimensionalRoutedAliasUpdateProcessorTest.java:219)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 16199 lines...]
[junit4] Suite: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[junit4] 2> 4645087 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001
[junit4] 2> 4645087 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4645088 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4645088 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4645192 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.ZkTestServer start zk server on port:64937
[junit4] 2> 4645192 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:64937
[junit4] 2> 4645192 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64937
[junit4] 2> 4645192 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 64937
[junit4] 2> 4645194 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645206 INFO (zkConnectionManagerCallback-24833-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645206 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645209 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645211 INFO (zkConnectionManagerCallback-24835-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645212 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645212 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645215 INFO (zkConnectionManagerCallback-24837-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645215 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645344 WARN (jetty-launcher-24838-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4645344 WARN (jetty-launcher-24838-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4645344 WARN (jetty-launcher-24838-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4645344 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4645344 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4645344 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4645344 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4645344 INFO (jetty-launcher-24838-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 4645344 INFO (jetty-launcher-24838-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 4645344 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4645345 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4645345 INFO (jetty-launcher-24838-thread-4) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 4645346 INFO (jetty-launcher-24838-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4645346 INFO (jetty-launcher-24838-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4645346 INFO (jetty-launcher-24838-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4645347 INFO (jetty-launcher-24838-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@498ddb54{/solr,null,AVAILABLE}
[junit4] 2> 4645350 WARN (jetty-launcher-24838-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4645350 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4645350 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4645351 INFO (jetty-launcher-24838-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 4645353 INFO (jetty-launcher-24838-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4645353 INFO (jetty-launcher-24838-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4645353 INFO (jetty-launcher-24838-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4645353 INFO (jetty-launcher-24838-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@48d943c5{/solr,null,AVAILABLE}
[junit4] 2> 4645356 INFO (jetty-launcher-24838-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4645356 INFO (jetty-launcher-24838-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4645356 INFO (jetty-launcher-24838-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4645357 INFO (jetty-launcher-24838-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@628c9cf0{/solr,null,AVAILABLE}
[junit4] 2> 4645363 INFO (jetty-launcher-24838-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4645363 INFO (jetty-launcher-24838-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4645363 INFO (jetty-launcher-24838-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4645364 INFO (jetty-launcher-24838-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@78330caa{/solr,null,AVAILABLE}
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16fc1f7a{SSL,[ssl, http/1.1]}{127.0.0.1:64942}
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-1) [ ] o.e.j.s.Server Started @4645443ms
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64942}
[junit4] 2> 4645365 ERROR (jetty-launcher-24838-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@77971c0d{SSL,[ssl, http/1.1]}{127.0.0.1:64943}
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-3) [ ] o.e.j.s.Server Started @4645443ms
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64943}
[junit4] 2> 4645365 ERROR (jetty-launcher-24838-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@72c4a68b{SSL,[ssl, http/1.1]}{127.0.0.1:64945}
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-2) [ ] o.e.j.s.Server Started @4645443ms
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64945}
[junit4] 2> 4645365 ERROR (jetty-launcher-24838-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4645365 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4645366 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 4645366 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4645366 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4645366 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-16T18:36:20.331Z
[junit4] 2> 4645367 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 4645367 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4645367 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4645367 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-16T18:36:20.332Z
[junit4] 2> 4645370 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 4645371 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4645371 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4645371 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-16T18:36:20.336Z
[junit4] 2> 4645373 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645373 INFO (jetty-launcher-24838-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2bbb6b7b{SSL,[ssl, http/1.1]}{127.0.0.1:64944}
[junit4] 2> 4645373 INFO (jetty-launcher-24838-thread-4) [ ] o.e.j.s.Server Started @4645451ms
[junit4] 2> 4645373 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64944}
[junit4] 2> 4645373 ERROR (jetty-launcher-24838-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4645373 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4645374 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 4645374 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4645374 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4645374 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-16T18:36:20.339Z
[junit4] 2> 4645375 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645375 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645376 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645384 INFO (zkConnectionManagerCallback-24840-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645385 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645386 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4645397 INFO (zkConnectionManagerCallback-24842-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645397 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645398 INFO (zkConnectionManagerCallback-24844-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645398 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645398 INFO (zkConnectionManagerCallback-24846-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645398 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4645399 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645399 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4645507 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4645557 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4645558 WARN (jetty-launcher-24838-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4645559 WARN (jetty-launcher-24838-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@331bd8ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645559 WARN (jetty-launcher-24838-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@331bd8ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645564 WARN (jetty-launcher-24838-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4645565 WARN (jetty-launcher-24838-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@190c83e6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645565 WARN (jetty-launcher-24838-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@190c83e6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645566 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64937/solr
[junit4] 2> 4645567 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645570 INFO (zkConnectionManagerCallback-24853-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645570 INFO (jetty-launcher-24838-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645717 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645719 INFO (zkConnectionManagerCallback-24856-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645719 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645848 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64942_solr
[junit4] 2> 4645849 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.Overseer Overseer (id=72134092639633416-127.0.0.1:64942_solr-n_0000000000) starting
[junit4] 2> 4645866 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645868 INFO (zkConnectionManagerCallback-24864-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645868 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645872 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64937/solr ready
[junit4] 2> 4645872 INFO (OverseerStateUpdate-72134092639633416-127.0.0.1:64942_solr-n_0000000000) [n:127.0.0.1:64942_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64942_solr
[junit4] 2> 4645876 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64942_solr
[junit4] 2> 4645880 INFO (zkCallback-24863-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4645880 INFO (zkCallback-24855-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4645889 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 4645912 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4645912 WARN (jetty-launcher-24838-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4645913 WARN (jetty-launcher-24838-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@32b31da8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645913 WARN (jetty-launcher-24838-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@32b31da8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645914 WARN (jetty-launcher-24838-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4645915 WARN (jetty-launcher-24838-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@66bf568c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645915 WARN (jetty-launcher-24838-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@66bf568c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4645916 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64937/solr
[junit4] 2> 4645917 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4645918 INFO (zkConnectionManagerCallback-24870-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4645919 INFO (jetty-launcher-24838-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4645947 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4645997 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64942.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646002 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64942.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646002 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64942.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646003 INFO (jetty-launcher-24838-thread-1) [n:127.0.0.1:64942_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node1/.
[junit4] 2> 4646025 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646027 INFO (zkConnectionManagerCallback-24874-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646027 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646034 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4646040 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64945_solr as DOWN
[junit4] 2> 4646041 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4646041 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64945_solr
[junit4] 2> 4646043 INFO (zkCallback-24863-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4646043 INFO (zkCallback-24855-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4646044 INFO (zkCallback-24873-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4646045 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646047 INFO (zkConnectionManagerCallback-24881-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646047 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646048 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4646050 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64937/solr ready
[junit4] 2> 4646050 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 4646087 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4646106 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64945.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646112 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64945.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646112 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64945.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646112 INFO (jetty-launcher-24838-thread-2) [n:127.0.0.1:64945_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/.
[junit4] 2> 4646152 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4646153 WARN (jetty-launcher-24838-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4646154 WARN (jetty-launcher-24838-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@fcfe32b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646154 WARN (jetty-launcher-24838-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@fcfe32b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646159 WARN (jetty-launcher-24838-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4646165 WARN (jetty-launcher-24838-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7221c1f2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646165 WARN (jetty-launcher-24838-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7221c1f2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646166 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64937/solr
[junit4] 2> 4646174 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646175 INFO (zkConnectionManagerCallback-24888-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646175 INFO (jetty-launcher-24838-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646281 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4646288 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646288 INFO (zkConnectionManagerCallback-24891-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646288 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646295 WARN (jetty-launcher-24838-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4646303 WARN (jetty-launcher-24838-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@365a5cc5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646303 WARN (jetty-launcher-24838-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@365a5cc5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646306 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4646310 WARN (jetty-launcher-24838-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 4646312 WARN (jetty-launcher-24838-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@b845279[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646312 WARN (jetty-launcher-24838-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@b845279[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4646313 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64943_solr as DOWN
[junit4] 2> 4646314 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4646314 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64943_solr
[junit4] 2> 4646315 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64937/solr
[junit4] 2> 4646317 INFO (zkCallback-24873-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4646317 INFO (zkCallback-24855-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4646317 INFO (zkCallback-24863-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4646317 INFO (zkCallback-24880-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4646317 INFO (zkCallback-24890-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4646320 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646321 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646321 INFO (zkConnectionManagerCallback-24898-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646321 INFO (zkConnectionManagerCallback-24903-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646321 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646321 INFO (jetty-launcher-24838-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646323 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4646426 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64937/solr ready
[junit4] 2> 4646426 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 4646427 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646428 INFO (zkConnectionManagerCallback-24905-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646429 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646442 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4646451 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64944_solr as DOWN
[junit4] 2> 4646453 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4646453 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64944_solr
[junit4] 2> 4646456 INFO (zkCallback-24873-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4646456 INFO (zkCallback-24890-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4646456 INFO (zkCallback-24863-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4646456 INFO (zkCallback-24855-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4646456 INFO (zkCallback-24880-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4646456 INFO (zkCallback-24902-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4646457 INFO (zkCallback-24904-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4646461 INFO (zkConnectionManagerCallback-24912-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646461 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646461 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646464 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4646468 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64937/solr ready
[junit4] 2> 4646469 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 4646486 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4646488 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4646515 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64944.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646515 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64943.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646521 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64944.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646521 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64943.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646521 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64944.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646521 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64943.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4646522 INFO (jetty-launcher-24838-thread-4) [n:127.0.0.1:64944_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node4/.
[junit4] 2> 4646522 INFO (jetty-launcher-24838-thread-3) [n:127.0.0.1:64943_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node3/.
[junit4] 2> 4646591 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 4646591 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4646594 INFO (zkConnectionManagerCallback-24919-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4646594 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4646596 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4646598 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64937/solr ready
[junit4] 2> 4646610 INFO (qtp558039051-65734) [n:127.0.0.1:64944_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646611 INFO (qtp558039051-65734) [n:127.0.0.1:64944_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 4646613 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.u.p.DimensionalRoutedAliasUpdateProcessorTest SolrClient: org.apache.solr.client.solrj.impl.CloudSolrClient@cb8e49d
[junit4] 2> 4646613 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.u.p.DimensionalRoutedAliasUpdateProcessorTest ClusterStateProvider org.apache.solr.client.solrj.impl.HttpClusterStateProvider@f9e0177
[junit4] 2> 4646624 INFO (qtp470053898-65716) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646624 INFO (qtp470053898-65716) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 4646624 INFO (qtp470053898-65717) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646630 INFO (qtp470053898-65717) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 4646630 INFO (qtp470053898-65718) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646632 INFO (qtp470053898-65718) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 4646632 INFO (qtp470053898-65713) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646634 INFO (qtp470053898-65713) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 4646637 INFO (qtp470053898-65716) [n:127.0.0.1:64942_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=_default&name=testTimeCat&action=CREATE&wt=javabin&version=2
[junit4] 2> 4646640 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , {
[junit4] 2> "name":"testTimeCat",
[junit4] 2> "baseConfigSet":"_default",
[junit4] 2> "immutable":"false",
[junit4] 2> "operation":"configsets:create"}
[junit4] 2> 4646642 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testTimeCat/managed-schema
[junit4] 2> 4646645 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testTimeCat/protwords.txt
[junit4] 2> 4646648 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testTimeCat/solrconfig.xml
[junit4] 2> 4646653 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testTimeCat/synonyms.txt
[junit4] 2> 4646656 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testTimeCat/stopwords.txt
[junit4] 2> 4646660 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testTimeCat/lang/contractions_it.txt
[junit4] 2> 4646663 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testTimeCat/lang/contractions_ca.txt
[junit4] 2> 4646667 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testTimeCat/lang/stemdict_nl.txt
[junit4] 2> 4646670 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testTimeCat/lang/stopwords_hy.txt
[junit4] 2> 4646674 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testTimeCat/lang/stopwords_no.txt
[junit4] 2> 4646677 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testTimeCat/lang/stopwords_id.txt
[junit4] 2> 4646681 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testTimeCat/lang/stopwords_et.txt
[junit4] 2> 4646684 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testTimeCat/lang/stopwords_da.txt
[junit4] 2> 4646691 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testTimeCat/lang/stopwords_ga.txt
[junit4] 2> 4646695 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testTimeCat/lang/stopwords_hi.txt
[junit4] 2> 4646699 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testTimeCat/lang/stopwords_ja.txt
[junit4] 2> 4646702 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testTimeCat/lang/stopwords_pt.txt
[junit4] 2> 4646706 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testTimeCat/lang/stopwords_hu.txt
[junit4] 2> 4646710 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testTimeCat/lang/stopwords_el.txt
[junit4] 2> 4646713 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testTimeCat/lang/stopwords_ru.txt
[junit4] 2> 4646717 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testTimeCat/lang/stopwords_tr.txt
[junit4] 2> 4646721 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testTimeCat/lang/stopwords_ar.txt
[junit4] 2> 4646724 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testTimeCat/lang/userdict_ja.txt
[junit4] 2> 4646727 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testTimeCat/lang/stopwords_eu.txt
[junit4] 2> 4646730 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testTimeCat/lang/stopwords_it.txt
[junit4] 2> 4646733 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testTimeCat/lang/stopwords_cz.txt
[junit4] 2> 4646736 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testTimeCat/lang/stoptags_ja.txt
[junit4] 2> 4646739 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testTimeCat/lang/contractions_fr.txt
[junit4] 2> 4646742 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testTimeCat/lang/stopwords_de.txt
[junit4] 2> 4646745 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testTimeCat/lang/stopwords_fa.txt
[junit4] 2> 4646748 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testTimeCat/lang/contractions_ga.txt
[junit4] 2> 4646752 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testTimeCat/lang/stopwords_ca.txt
[junit4] 2> 4646755 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testTimeCat/lang/stopwords_nl.txt
[junit4] 2> 4646758 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testTimeCat/lang/stopwords_bg.txt
[junit4] 2> 4646763 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testTimeCat/lang/stopwords_en.txt
[junit4] 2> 4646767 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testTimeCat/lang/stopwords_es.txt
[junit4] 2> 4646770 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testTimeCat/lang/stopwords_lv.txt
[junit4] 2> 4646773 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testTimeCat/lang/stopwords_ro.txt
[junit4] 2> 4646776 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testTimeCat/lang/stopwords_th.txt
[junit4] 2> 4646779 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testTimeCat/lang/stopwords_fr.txt
[junit4] 2> 4646782 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testTimeCat/lang/stopwords_sv.txt
[junit4] 2> 4646786 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testTimeCat/lang/stopwords_fi.txt
[junit4] 2> 4646789 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testTimeCat/lang/hyphenations_ga.txt
[junit4] 2> 4646793 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testTimeCat/lang/stopwords_gl.txt
[junit4] 2> 4646796 INFO (OverseerThreadFactory-21063-thread-1-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testTimeCat/params.json
[junit4] 2> 4646801 INFO (qtp470053898-65716) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/configs params={baseConfigSet=_default&name=testTimeCat&action=CREATE&wt=javabin&version=2} status=0 QTime=164
[junit4] 2> 4646802 INFO (qtp470053898-65717) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646803 INFO (qtp470053898-65717) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 4646804 INFO (qtp470053898-65718) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646806 INFO (qtp470053898-65718) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 4646806 INFO (qtp470053898-65713) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646808 INFO (qtp470053898-65713) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 4646808 INFO (qtp470053898-65714) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646810 INFO (qtp470053898-65714) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 4646811 INFO (qtp470053898-65715) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=testTimeCat&name=testTimeCat&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4646816 INFO (OverseerThreadFactory-21063-thread-2-processing-n:127.0.0.1:64942_solr) [n:127.0.0.1:64942_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testTimeCat
[junit4] 2> 4646816 INFO (OverseerCollectionConfigSetProcessor-72134092639633416-127.0.0.1:64942_solr-n_0000000000) [n:127.0.0.1:64942_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> 4646941 INFO (OverseerStateUpdate-72134092639633416-127.0.0.1:64942_solr-n_0000000000) [n:127.0.0.1:64942_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testTimeCat",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testTimeCat_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:64945/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 4647165 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr x:testTimeCat_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testTimeCat&newCollection=true&name=testTimeCat_shard1_replica_n1&action=CREATE&numShards=1&collection=testTimeCat&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4648195 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4648195 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4648197 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 4648197 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4648197 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 4648197 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4648198 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 4648198 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4648198 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 4648198 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4648198 WARN (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4648199 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
[junit4] 2> 4648230 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.IndexSchema [testTimeCat_shard1_replica_n1] Schema name=default-config
[junit4] 2> 4648430 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 4648512 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testTimeCat_shard1_replica_n1' using configuration from collection testTimeCat, trusted=true
[junit4] 2> 4648512 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64945.solr.core.testTimeCat.shard1.replica_n1' (registry 'solr.core.testTimeCat.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e438625
[junit4] 2> 4648513 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrCore [[testTimeCat_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/./testTimeCat_shard1_replica_n1/data/]
[junit4] 2> 4648534 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 4648632 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4648632 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4648634 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 4648634 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4648636 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@932df65[testTimeCat_shard1_replica_n1] main]
[junit4] 2> 4648638 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testTimeCat
[junit4] 2> 4648639 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testTimeCat
[junit4] 2> 4648639 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testTimeCat/managed-schema
[junit4] 2> 4648640 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 4648640 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 4648640 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_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> 4648642 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4648642 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1644858162394693632
[junit4] 2> 4648644 INFO (searcherExecutor-21091-thread-1-processing-n:127.0.0.1:64945_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@932df65[testTimeCat_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4648644 INFO (searcherExecutor-21091-thread-1-processing-n:127.0.0.1:64945_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 4648644 INFO (searcherExecutor-21091-thread-1-processing-n:127.0.0.1:64945_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 4648645 INFO (searcherExecutor-21091-thread-1-processing-n:127.0.0.1:64945_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrCore [testTimeCat_shard1_replica_n1] Registered new searcher Searcher@932df65[testTimeCat_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4648650 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testTimeCat/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 4648650 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testTimeCat/leaders/shard1
[junit4] 2> 4648657 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4648657 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4648657 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:64945/solr/testTimeCat_shard1_replica_n1/
[junit4] 2> 4648657 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 4648658 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:64945/solr/testTimeCat_shard1_replica_n1/ has no replicas
[junit4] 2> 4648658 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testTimeCat/leaders/shard1/leader after winning as /collections/testTimeCat/leader_elect/shard1/election/72134092639633419-core_node2-n_0000000000
[junit4] 2> 4648661 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:64945/solr/testTimeCat_shard1_replica_n1/ shard1
[junit4] 2> 4648774 INFO (zkCallback-24873-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4648774 INFO (zkCallback-24873-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4648776 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 4648779 INFO (qtp2017250811-65744) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testTimeCat&newCollection=true&name=testTimeCat_shard1_replica_n1&action=CREATE&numShards=1&collection=testTimeCat&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1614
[junit4] 2> 4648783 INFO (qtp470053898-65715) [n:127.0.0.1:64942_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> 4648829 INFO (OverseerCollectionConfigSetProcessor-72134092639633416-127.0.0.1:64942_solr-n_0000000000) [n:127.0.0.1:64942_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> 4648886 INFO (zkCallback-24855-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4648886 INFO (zkCallback-24873-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4648886 INFO (zkCallback-24873-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4648886 INFO (zkCallback-24873-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4648888 INFO (qtp470053898-65715) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=testTimeCat&name=testTimeCat&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2077
[junit4] 2> 4648890 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[EF0F862228C0CE96]) [ ] o.a.s.c.SolrCloudTestCase waitForState (testTimeCat): waiting for collections to be created
[junit4] 2> 4649397 INFO (qtp470053898-65716) [n:127.0.0.1:64942_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4649401 INFO (qtp470053898-65716) [n:127.0.0.1:64942_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 4649459 INFO (qtp2017250811-65742) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_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":"testTimeCat"}}, {"add-updateprocessor":{
[junit4] 2> "name":"inc",
[junit4] 2> "class":"org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest$IncrementURPFactory",
[junit4] 2> "fieldName":"integer_i"}}]
[junit4] 2> 4649459 INFO (qtp2017250811-65742) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_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 testTimeCat
[junit4] 2> 4649461 INFO (Thread-8439) [ ] o.a.s.c.SolrCore config update listener called for core testTimeCat_shard1_replica_n1
[junit4] 2> 4649463 INFO (solrHandlerExecutor-21096-thread-1-processing-n:127.0.0.1:64945_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:64945_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 4649463 INFO (Thread-8439) [ ] o.a.s.c.SolrCore core reload testTimeCat_shard1_replica_n1
[junit4] 2> 4649479 INFO (Thread-8439) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4649479 INFO (Thread-8439) [ ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4649480 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 4649480 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4649481 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 4649481 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4649481 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 4649481 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4649482 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_EF0F862228C0CE96-001/tempDir-001/node2/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 4649482 WARN (Thread-8439) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProce
[...truncated too long message...]
/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/temp
[junit4] <JUnit4> says hallo! Master seed: EF0F862228C0CE96
[junit4] Executing 5 suites with 2 JVMs.
[junit4]
[junit4] Started J1 PID(6924@serv1-vm2.sd-datasolutions.de).
[junit4] Started J0 PID(6925@serv1-vm2.sd-datasolutions.de).
[junit4] HEARTBEAT J1 PID(6924@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:13:30, stalled for 63.1s at: DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] HEARTBEAT J0 PID(6925@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:13:30, stalled for 62.9s at: DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] HEARTBEAT J1 PID(6924@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:15:30, stalled for 63.7s at: DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] HEARTBEAT J0 PID(6925@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:15:30, stalled for 60.1s at: DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Suite: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[junit4] OK 119s J1 | DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] OK 84.1s J1 | DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Completed [1/5] on J1 in 210.88s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[junit4] OK 123s J0 | DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] OK 92.5s J0 | DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Completed [2/5] on J0 in 222.85s, 2 tests
[junit4]
[junit4] HEARTBEAT J1 PID(6924@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:16:54, stalled for 62.2s at: DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] HEARTBEAT J0 PID(6925@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:17:06, stalled for 62.2s at: DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] HEARTBEAT J1 PID(6924@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:18:30, stalled for 71.0s at: DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] HEARTBEAT J0 PID(6925@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:18:30, stalled for 62.2s at: DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Suite: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[junit4] OK 87.4s J1 | DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] OK 84.0s J1 | DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Completed [3/5] on J1 in 171.68s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[junit4] OK 85.0s J0 | DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] OK 76.4s J0 | DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Completed [4/5] on J0 in 161.79s, 2 tests
[junit4]
[junit4] HEARTBEAT J1 PID(6924@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:19:54, stalled for 70.1s at: DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] HEARTBEAT J1 PID(6924@serv1-vm2.sd-datasolutions.de): 2019-09-16T19:21:06, stalled for 60.4s at: DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Suite: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[junit4] OK 82.0s J1 | DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
[junit4] OK 83.6s J1 | DimensionalRoutedAliasUpdateProcessorTest.testCatTime
[junit4] Completed [5/5] on J1 in 165.92s, 2 tests
[junit4]
[junit4] JVM J0: 0.95 .. 387.13 = 386.18s
[junit4] JVM J1: 0.95 .. 552.42 = 551.47s
[junit4] Execution time total: 9 minutes 12 seconds
[junit4] Tests summary: 5 suites, 10 tests
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 275.60s | org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest
[junit4:tophints] 269.06s | org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 256.77s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 228.46s | org.apache.solr.search.facet.TestJsonFacets
[junit4:tophints] 187.04s | org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 9 minutes 15 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2804)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2683)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2664)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3051)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to MacOSX VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:356)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1071)
at hudson.FilePath.act(FilePath.java:1060)
at hudson.FilePath.validateAntFileMask(FilePath.java:2662)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1840)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3053)
at hudson.remoting.UserRequest.perform(UserRequest.java:211)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1073)
at hudson.FilePath.act(FilePath.java:1060)
at hudson.FilePath.validateAntFileMask(FilePath.java:2662)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1840)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=2f701c6787f9f216e5065e7f7fa1e7ea01126e22, workspace=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX
[WARNINGS] Computing warning deltas based on reference build #329
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 332 -
Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/332/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
All tests passed
Build Log:
[...truncated 56547 lines...]
[javadoc] Generating Javadoc
[javadoc] Javadoc execution
[javadoc] Loading source files for package org.apache.lucene.analysis.ja...
[javadoc] Loading source files for package org.apache.lucene.analysis.ja.dict...
[javadoc] Loading source files for package org.apache.lucene.analysis.ja.tokenattributes...
[javadoc] Loading source files for package org.apache.lucene.analysis.ja.util...
[javadoc] Constructing Javadoc information...
[javadoc] Standard Doclet version 1.8.0_201
[javadoc] Building tree for all the packages and classes...
[javadoc] /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/analysis/kuromoji/src/java/org/apache/lucene/analysis/ja/util/DictionaryBuilder.java:36: error: unknown tag: lucene.experimenal
[javadoc] * @lucene.experimenal
[javadoc] ^
[javadoc] Building index for all the packages and classes...
[javadoc] Building index for all classes...
[javadoc] Generating /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/docs/analyzers-kuromoji/help-doc.html...
[javadoc] Note: Custom tags that were not seen: @lucene.internal
[javadoc] 1 error
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/build.xml:634: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/build.xml:101: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build.xml:142: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build.xml:250: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/common-build.xml:2272: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/analysis/build.xml:141: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/analysis/build.xml:38: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/analysis/analysis-module-build.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/module-build.xml:87: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/common-build.xml:2201: Javadoc returned 1
Total time: 132 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-12.0.1) - Build # 331 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/331/
Java: 64bit/jdk-12.0.1 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.handler.TestContainerReqHandler.testPackageAPI
Error Message:
attempt: 9 Mismatch for value : '[requestHandler]' in response { "responseHeader":{ "status":0, "QTime":0}, "metadata":{"version":3}, "runtimeLib":[{ "name":"global", "url":"http://localhost:52410/jar3.jar", "sha256":"20e0bfaec71b2e93c4da9f2ed3745dda04dc3fc915b66cc0275863982e73b2a3", "znodeVersion":3}], "requestHandler":[{ "name":"bar", "znodeVersion":3, "class":"org.apache.solr.core.RuntimeLibReqHandler", "package":"global"}]}
Stack Trace:
java.lang.AssertionError: attempt: 9 Mismatch for value : '[requestHandler]' in response {
"responseHeader":{
"status":0,
"QTime":0},
"metadata":{"version":3},
"runtimeLib":[{
"name":"global",
"url":"http://localhost:52410/jar3.jar",
"sha256":"20e0bfaec71b2e93c4da9f2ed3745dda04dc3fc915b66cc0275863982e73b2a3",
"znodeVersion":3}],
"requestHandler":[{
"name":"bar",
"znodeVersion":3,
"class":"org.apache.solr.core.RuntimeLibReqHandler",
"package":"global"}]}
at __randomizedtesting.SeedInfo.seed([E70E8D55030F58A2:9501981BAF5402B2]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.handler.TestContainerReqHandler.assertResponseValues(TestContainerReqHandler.java:121)
at org.apache.solr.handler.TestContainerReqHandler.testPackageAPI(TestContainerReqHandler.java:236)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
FAILED: org.apache.solr.handler.TestContainerReqHandler.testPackageAPI
Error Message:
attempt: 9 Mismatch for value : '[requestHandler]' in response { "responseHeader":{ "status":0, "QTime":0}, "metadata":{"version":3}, "runtimeLib":[{ "name":"global", "url":"http://localhost:61079/jar3.jar", "sha256":"20e0bfaec71b2e93c4da9f2ed3745dda04dc3fc915b66cc0275863982e73b2a3", "znodeVersion":3}], "requestHandler":[{ "name":"bar", "znodeVersion":3, "class":"org.apache.solr.core.RuntimeLibReqHandler", "package":"global"}]}
Stack Trace:
java.lang.AssertionError: attempt: 9 Mismatch for value : '[requestHandler]' in response {
"responseHeader":{
"status":0,
"QTime":0},
"metadata":{"version":3},
"runtimeLib":[{
"name":"global",
"url":"http://localhost:61079/jar3.jar",
"sha256":"20e0bfaec71b2e93c4da9f2ed3745dda04dc3fc915b66cc0275863982e73b2a3",
"znodeVersion":3}],
"requestHandler":[{
"name":"bar",
"znodeVersion":3,
"class":"org.apache.solr.core.RuntimeLibReqHandler",
"package":"global"}]}
at __randomizedtesting.SeedInfo.seed([E70E8D55030F58A2:9501981BAF5402B2]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.handler.TestContainerReqHandler.assertResponseValues(TestContainerReqHandler.java:121)
at org.apache.solr.handler.TestContainerReqHandler.testPackageAPI(TestContainerReqHandler.java:236)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
Build Log:
[...truncated 13809 lines...]
[junit4] Suite: org.apache.solr.handler.TestContainerReqHandler
[junit4] 2> 1368491 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/data-dir-64-001
[junit4] 2> 1368491 WARN (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 1368491 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1368492 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None") w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1368492 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1368494 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCacheLoadFromPackage
[junit4] 2> 1368503 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1368506 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a2950ee{HTTP/1.1,[http/1.1]}{0.0.0.0:60871}
[junit4] 2> 1368506 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.e.j.s.Server Started @1368577ms
[junit4] 2> 1368507 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001
[junit4] 2> 1368507 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1368508 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1368508 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1368611 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer start zk server on port:60872
[junit4] 2> 1368611 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:60872
[junit4] 2> 1368611 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60872
[junit4] 2> 1368611 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60872
[junit4] 2> 1368614 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1368626 INFO (zkConnectionManagerCallback-8334-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1368626 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1368630 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1368632 INFO (zkConnectionManagerCallback-8336-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1368632 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1368640 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1368642 INFO (zkConnectionManagerCallback-8338-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1368642 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1368746 WARN (jetty-launcher-8339-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1368746 WARN (jetty-launcher-8339-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1368746 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1368746 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1368747 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1368747 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1368747 INFO (jetty-launcher-8339-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1368747 INFO (jetty-launcher-8339-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1368747 WARN (jetty-launcher-8339-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1368747 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1368747 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1368747 INFO (jetty-launcher-8339-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1368748 WARN (jetty-launcher-8339-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1368748 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1368748 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1368748 INFO (jetty-launcher-8339-thread-4) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 1368749 INFO (jetty-launcher-8339-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1368749 INFO (jetty-launcher-8339-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1368749 INFO (jetty-launcher-8339-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1368750 INFO (jetty-launcher-8339-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d3ce5f8{/solr,null,AVAILABLE}
[junit4] 2> 1368750 INFO (jetty-launcher-8339-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a50e30f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60879}
[junit4] 2> 1368750 INFO (jetty-launcher-8339-thread-1) [ ] o.e.j.s.Server Started @1368821ms
[junit4] 2> 1368750 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60879}
[junit4] 2> 1368750 ERROR (jetty-launcher-8339-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1368750 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1368751 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 1368751 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1368751 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1368751 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-17T05:13:35.207284Z
[junit4] 2> 1368752 INFO (jetty-launcher-8339-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1368752 INFO (jetty-launcher-8339-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1368752 INFO (jetty-launcher-8339-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2a80b811{/solr,null,AVAILABLE}
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7e6995e9{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60880}
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.e.j.s.Server Started @1368824ms
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60880}
[junit4] 2> 1368753 ERROR (jetty-launcher-8339-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1368753 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-17T05:13:35.209863Z
[junit4] 2> 1368754 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1368756 INFO (jetty-launcher-8339-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1368756 INFO (jetty-launcher-8339-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1368756 INFO (jetty-launcher-8339-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1368757 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1368758 INFO (jetty-launcher-8339-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1368758 INFO (jetty-launcher-8339-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1368758 INFO (jetty-launcher-8339-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1368758 INFO (zkConnectionManagerCallback-8343-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1368758 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1368758 INFO (jetty-launcher-8339-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4b2c9f6d{/solr,null,AVAILABLE}
[junit4] 2> 1368759 INFO (jetty-launcher-8339-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@738df719{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60883}
[junit4] 2> 1368759 INFO (jetty-launcher-8339-thread-3) [ ] o.e.j.s.Server Started @1368830ms
[junit4] 2> 1368759 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60883}
[junit4] 2> 1368759 INFO (jetty-launcher-8339-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f52656b{/solr,null,AVAILABLE}
[junit4] 2> 1368759 ERROR (jetty-launcher-8339-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1368759 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-17T05:13:35.216125Z
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5559f9ca{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60884}
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.e.j.s.Server Started @1368831ms
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60884}
[junit4] 2> 1368760 ERROR (jetty-launcher-8339-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1368760 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-17T05:13:35.216914Z
[junit4] 2> 1368761 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1368761 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1368762 INFO (zkConnectionManagerCallback-8341-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1368762 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1368765 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1368765 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1368766 INFO (zkConnectionManagerCallback-8345-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1368766 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1368769 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1368770 INFO (zkConnectionManagerCallback-8347-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1368770 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1368772 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1369047 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1369047 WARN (jetty-launcher-8339-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3a8daaa5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369047 WARN (jetty-launcher-8339-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3a8daaa5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369051 WARN (jetty-launcher-8339-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@721f81d7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369051 WARN (jetty-launcher-8339-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@721f81d7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369052 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60872/solr
[junit4] 2> 1369053 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369056 INFO (zkConnectionManagerCallback-8354-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369056 INFO (jetty-launcher-8339-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369111 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1369112 WARN (jetty-launcher-8339-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@128ab4ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369112 WARN (jetty-launcher-8339-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@128ab4ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369114 WARN (jetty-launcher-8339-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@456486ef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369114 WARN (jetty-launcher-8339-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@456486ef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369115 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60872/solr
[junit4] 2> 1369116 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369118 INFO (zkConnectionManagerCallback-8362-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369118 INFO (jetty-launcher-8339-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369223 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1369224 WARN (jetty-launcher-8339-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@75bdd242[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369224 WARN (jetty-launcher-8339-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@75bdd242[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369227 WARN (jetty-launcher-8339-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2fcf325[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369227 WARN (jetty-launcher-8339-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2fcf325[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369228 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60872/solr
[junit4] 2> 1369228 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369230 INFO (zkConnectionManagerCallback-8369-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369230 INFO (jetty-launcher-8339-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369257 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1369258 WARN (jetty-launcher-8339-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1853cea1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369258 WARN (jetty-launcher-8339-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1853cea1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369260 WARN (jetty-launcher-8339-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d2976e3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369260 WARN (jetty-launcher-8339-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d2976e3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1369261 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60872/solr
[junit4] 2> 1369261 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369263 INFO (zkConnectionManagerCallback-8375-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369263 INFO (jetty-launcher-8339-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369278 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369280 INFO (zkConnectionManagerCallback-8377-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369280 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369388 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369390 INFO (zkConnectionManagerCallback-8379-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369390 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369491 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369493 INFO (zkConnectionManagerCallback-8381-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369493 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369599 INFO (zkConnectionManagerCallback-8383-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369599 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369599 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369629 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369631 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369636 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 1369640 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369659 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60883_solr
[junit4] 2> 1369662 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.Overseer Overseer (id=72136598403153934-127.0.0.1:60883_solr-n_0000000000) starting
[junit4] 2> 1369694 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369695 INFO (zkConnectionManagerCallback-8396-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369695 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369696 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369697 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369699 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 1369700 DEBUG (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369702 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60872/solr ready
[junit4] 2> 1369703 INFO (OverseerStateUpdate-72136598403153934-127.0.0.1:60883_solr-n_0000000000) [n:127.0.0.1:60883_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:60883_solr
[junit4] 2> 1369705 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60883_solr
[junit4] 2> 1369708 DEBUG (OverseerStateUpdate-72136598403153934-127.0.0.1:60883_solr-n_0000000000) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 1369709 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1369709 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1369710 DEBUG (OverseerStateUpdate-72136598403153934-127.0.0.1:60883_solr-n_0000000000) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369710 INFO (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1369710 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60883_solr]
[junit4] 2> 1369711 INFO (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1369711 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60883_solr]
[junit4] 2> 1369715 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 1369737 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1369750 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369752 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369753 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1369753 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60883_solr]
[junit4] 2> 1369754 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369760 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60883.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369763 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60884_solr as DOWN
[junit4] 2> 1369765 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1369765 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60884_solr
[junit4] 2> 1369767 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1369767 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1369767 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1369768 INFO (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1369768 INFO (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1369768 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr] -> [127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369768 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr] -> [127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369769 INFO (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1369769 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr] -> [127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369781 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369784 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60883.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369785 INFO (zkConnectionManagerCallback-8402-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369785 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369785 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60883.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369785 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369788 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369788 INFO (jetty-launcher-8339-thread-3) [n:127.0.0.1:60883_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node3/.
[junit4] 2> 1369791 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1369791 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369792 DEBUG (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369794 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60872/solr ready
[junit4] 2> 1369794 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 1369818 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1369823 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369824 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369827 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1369827 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369828 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369848 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60880_solr as DOWN
[junit4] 2> 1369849 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1369849 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60880_solr
[junit4] 2> 1369850 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1369850 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1369851 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1369851 INFO (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369851 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369851 INFO (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369851 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369851 INFO (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369851 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369852 DEBUG (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1369852 DEBUG (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1369853 INFO (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369853 DEBUG (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369853 INFO (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369853 DEBUG (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369855 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369855 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60884.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369857 INFO (zkConnectionManagerCallback-8408-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369857 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369857 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369859 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369860 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1369860 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369861 DEBUG (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369863 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60872/solr ready
[junit4] 2> 1369864 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369865 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369865 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 1369866 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1369866 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369867 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369870 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60884.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369870 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60884.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369871 INFO (jetty-launcher-8339-thread-4) [n:127.0.0.1:60884_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node4/.
[junit4] 2> 1369887 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1369888 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60879_solr as DOWN
[junit4] 2> 1369897 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1369897 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60879_solr
[junit4] 2> 1369902 DEBUG (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1369902 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1369902 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1369902 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1369903 DEBUG (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1369903 DEBUG (zkCallback-8407-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1369903 DEBUG (zkCallback-8380-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1369904 INFO (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1369904 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369904 INFO (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1369904 DEBUG (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369904 INFO (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1369904 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369904 INFO (zkCallback-8407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1369904 DEBUG (zkCallback-8407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369904 INFO (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1369904 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369904 INFO (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1369904 DEBUG (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369904 INFO (zkCallback-8380-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1369904 DEBUG (zkCallback-8380-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369906 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1369909 INFO (zkConnectionManagerCallback-8414-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1369909 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1369909 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1369911 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1369911 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1369911 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1369912 DEBUG (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1369913 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60872/solr ready
[junit4] 2> 1369914 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 1369919 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60880.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369942 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60880.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369942 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60880.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369944 INFO (jetty-launcher-8339-thread-2) [n:127.0.0.1:60880_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node2/.
[junit4] 2> 1369954 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1369976 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60879.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369985 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60879.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369985 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60879.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1369987 INFO (jetty-launcher-8339-thread-1) [n:127.0.0.1:60879_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node1/.
[junit4] 2> 1370024 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1370025 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1370027 INFO (zkConnectionManagerCallback-8420-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1370028 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1370028 DEBUG (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1370029 DEBUG (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1370030 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1370030 DEBUG (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:60879_solr, 127.0.0.1:60880_solr, 127.0.0.1:60883_solr, 127.0.0.1:60884_solr]
[junit4] 2> 1370031 DEBUG (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 1370032 INFO (TEST-TestContainerReqHandler.testCacheLoadFromPackage-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60872/solr ready
[junit4] 2> 1370055 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 1370059 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370059 DEBUG (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370059 DEBUG (zkCallback-8407-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370060 DEBUG (zkCallback-8419-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370060 DEBUG (zkCallback-8413-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370060 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370060 INFO (zkCallback-8382-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 1370060 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370060 INFO (zkCallback-8378-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 1370061 DEBUG (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370061 INFO (zkCallback-8376-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 1370061 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 1370063 DEBUG (zkCallback-8380-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}} to version 0
[junit4] 2> 1370063 INFO (zkCallback-8380-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 1370067 INFO (zkCallback-8382-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 1370067 INFO (zkCallback-8378-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 1370067 INFO (zkCallback-8380-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 1370068 INFO (zkCallback-8376-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 1370068 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 1370111 INFO (zkCallback-8382-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:60871/jar1.jar]
[junit4] 2> 1370111 INFO (zkCallback-8378-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:60871/jar1.jar]
[junit4] 2> 1370115 INFO (zkCallback-8380-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:60871/jar1.jar]
[junit4] 2> 1370122 INFO (zkCallback-8376-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:60871/jar1.jar]
[junit4] 2> 1370122 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:60871/jar1.jar]
[junit4] 2> 1370122 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.h.a.CollectionHandlerApi current version of clusterprops.json is 0 , trying to get every node to update
[junit4] 2> 1370122 DEBUG (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.h.a.CollectionHandlerApi The current clusterprops.json: {package={cache_pkg={name=cache_pkg, url=http://localhost:60871/jar1.jar, sha256=32e8b5b2a95ea306538b52017f0954aa1b0f8a8b2d0acbc498fd0e66a223f7bd}}}
[junit4] 2> 1370124 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.h.a.CollectionHandlerApi Waiting up to 30 secs for 4 nodes to update clusterprops to be of version 0
[junit4] 2> 1370127 INFO (solrHandlerExecutor-5628-thread-1-processing-n:127.0.0.1:60880_solr) [n:127.0.0.1:60880_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1370127 INFO (solrHandlerExecutor-5628-thread-2-processing-n:127.0.0.1:60880_solr) [n:127.0.0.1:60880_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1370127 INFO (solrHandlerExecutor-5628-thread-3-processing-n:127.0.0.1:60880_solr) [n:127.0.0.1:60880_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1370127 INFO (solrHandlerExecutor-5628-thread-4-processing-n:127.0.0.1:60880_solr) [n:127.0.0.1:60880_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1370233 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 1370233 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 1370233 DEBUG (qtp1537088713-20936) [n:127.0.0.1:60884_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 1370233 DEBUG (qtp1537088713-20936) [n:127.0.0.1:60884_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 1370233 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1370233 INFO (qtp1537088713-20936) [n:127.0.0.1:60884_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1370233 DEBUG (qtp2102003392-20929) [n:127.0.0.1:60883_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 1370233 DEBUG (qtp2102003392-20929) [n:127.0.0.1:60883_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 1370233 INFO (qtp2102003392-20929) [n:127.0.0.1:60883_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1370236 DEBUG (qtp1890481548-20918) [n:127.0.0.1:60880_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 1370236 DEBUG (qtp1890481548-20918) [n:127.0.0.1:60880_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 1370236 INFO (qtp1890481548-20918) [n:127.0.0.1:60880_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1370237 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.h.a.CollectionHandlerApi Took 114.0ms to update the clusterprops to be of version 0 on 4 nodes
[junit4] 2> 1370237 INFO (qtp1890481548-20916) [n:127.0.0.1:60880_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster params={wt=javabin&version=2} status=0 QTime=197
[junit4] 2> 1370240 INFO (qtp1687618029-20912) [n:127.0.0.1:60879_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=globalCacheColl&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1370245 INFO (OverseerThreadFactory-5611-thread-1-processing-n:127.0.0.1:60883_solr) [n:127.0.0.1:60883_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection globalCacheColl
[junit4] 2> 1370249 DEBUG (zkCallback-8419-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8413-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8407-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8380-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370249 DEBUG (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1370251 DEBUG (zkCallback-8382-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8413-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8380-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8376-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8401-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8407-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8419-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370251 DEBUG (zkCallback-8395-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370383 INFO (OverseerStateUpdate-72136598403153934-127.0.0.1:60883_solr-n_0000000000) [n:127.0.0.1:60883_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"globalCacheColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"globalCacheColl_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60884/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1370388 INFO (OverseerStateUpdate-72136598403153934-127.0.0.1:60883_solr-n_0000000000) [n:127.0.0.1:60883_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"globalCacheColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"globalCacheColl_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60879/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1370599 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr x:globalCacheColl_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=globalCacheColl_shard2_replica_n2&action=CREATE&numShards=2&collection=globalCacheColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1370599 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr x:globalCacheColl_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=globalCacheColl_shard1_replica_n1&action=CREATE&numShards=2&collection=globalCacheColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1370601 DEBUG (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Add data for [globalCacheColl] ver [1]
[junit4] 2> 1370601 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.c.ZkStateReader Add data for [globalCacheColl] ver [1]
[junit4] 2> 1370601 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [1] total [1]
[junit4] 2> 1370601 DEBUG (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [1] total [1]
[junit4] 2> 1370601 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370601 DEBUG (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [1] total [1]
[junit4] 2> 1370713 DEBUG (zkCallback-8378-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection globalCacheColl
[junit4] 2> 1370713 DEBUG (zkCallback-8380-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection globalCacheColl
[junit4] 2> 1371619 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.c.ZkStateReader Loading collection config from: [/collections/globalCacheColl]
[junit4] 2> 1371619 DEBUG (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Loading collection config from: [/collections/globalCacheColl]
[junit4] 2> 1371622 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.c.ZkStateReader path=[/configs/conf] [configName]=[conf] specified config exists in ZooKeeper
[junit4] 2> 1371622 DEBUG (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader path=[/configs/conf] [configName]=[conf] specified config exists in ZooKeeper
[junit4] 2> 1371650 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
[junit4] 2> 1371651 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.0
[junit4] 2> 1371663 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.s.IndexSchema [globalCacheColl_shard2_replica_n2] Schema name=minimal
[junit4] 2> 1371666 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1371666 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'globalCacheColl_shard2_replica_n2' using configuration from collection globalCacheColl, trusted=true
[junit4] 2> 1371666 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60879.solr.core.globalCacheColl.shard2.replica_n2' (registry 'solr.core.globalCacheColl.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1371666 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.SolrCore [[globalCacheColl_shard2_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node1/globalCacheColl_shard2_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node1/./globalCacheColl_shard2_replica_n2/data/]
[junit4] 2> 1371669 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.s.IndexSchema [globalCacheColl_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1371670 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1371670 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'globalCacheColl_shard1_replica_n1' using configuration from collection globalCacheColl, trusted=true
[junit4] 2> 1371670 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60884.solr.core.globalCacheColl.shard1.replica_n1' (registry 'solr.core.globalCacheColl.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a40fafb
[junit4] 2> 1371670 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.SolrCore [[globalCacheColl_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node4/globalCacheColl_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001/tempDir-001/node4/./globalCacheColl_shard1_replica_n1/data/]
[junit4] 2> 1371762 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1371762 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1371763 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1371763 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1371764 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1371764 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1371765 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1371765 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1371766 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7fcd10c[globalCacheColl_shard1_replica_n1] main]
[junit4] 2> 1371766 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@51bdd3c3[globalCacheColl_shard2_replica_n2] main]
[junit4] 2> 1371786 INFO (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.MemClassLoader Defining_class org.apache.solr.core.MyDocCache from runtime jar cache_pkg
[junit4] 2> 1371786 INFO (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:globalCacheColl_shard1_replica_n1 ] o.a.s.c.MemClassLoader Defining_class org.apache.solr.core.MyDocCache from runtime jar cache_pkg
[junit4] 2> 1371792 DEBUG (qtp1687618029-20908) [n:127.0.0.1:60879_solr c:globalCacheColl s:shard2 r:core_node4 x:globalCacheColl_shard2_replica_n2 ] o.a.s.c.c.ZkStateReader Loading collection config from: [/collections/globalCacheColl]
[junit4] 2> 1371792 DEBUG (qtp1537088713-20941) [n:127.0.0.1:60884_solr c:globalCacheColl s:shard1 r:core_node3 x:glo
[...truncated too long message...]
live nodes size: [4])
[junit4] 2> 126067 INFO (closeThreadPool-2150-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72136887754883085-127.0.0.1:52468_solr-n_0000000000) closing
[junit4] 2> 126173 INFO (jetty-closer-2137-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72136887754883085-127.0.0.1:52468_solr-n_0000000000) closing
[junit4] 2> 126277 INFO (zkCallback-2107-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 126277 INFO (zkCallback-2119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 126277 DEBUG (zkCallback-2119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:52468_solr, 127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr] -> [127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr]
[junit4] 2> 126277 DEBUG (zkCallback-2107-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:52468_solr, 127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr] -> [127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr]
[junit4] 2> 126278 INFO (zkCallback-2086-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 126278 INFO (zkCallback-2114-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 126278 DEBUG (zkCallback-2086-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:52468_solr, 127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr] -> [127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr]
[junit4] 2> 126278 DEBUG (zkCallback-2114-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:52468_solr, 127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr] -> [127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr]
[junit4] 2> 126278 INFO (zkCallback-2104-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 126278 DEBUG (zkCallback-2104-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:52468_solr, 127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr] -> [127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr]
[junit4] 2> 126278 INFO (zkCallback-2084-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 126278 DEBUG (zkCallback-2084-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:52468_solr, 127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr] -> [127.0.0.1:52469_solr, 127.0.0.1:52470_solr, 127.0.0.1:52472_solr]
[junit4] 2> 126279 INFO (jetty-closer-2137-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3d661cc3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 126280 INFO (jetty-closer-2137-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@740d3f45{/solr,null,UNAVAILABLE}
[junit4] 2> 126280 INFO (jetty-closer-2137-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 126486 INFO (jetty-closer-2137-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1f04b41{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 126487 INFO (jetty-closer-2137-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@35b4f7b3{/solr,null,UNAVAILABLE}
[junit4] 2> 126487 INFO (jetty-closer-2137-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 126691 INFO (jetty-closer-2137-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@155bd7ed{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 126691 INFO (jetty-closer-2137-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7cbb1890{/solr,null,UNAVAILABLE}
[junit4] 2> 126692 INFO (jetty-closer-2137-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 126895 INFO (jetty-closer-2137-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1d711d1b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 126896 INFO (jetty-closer-2137-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3219dfed{/solr,null,UNAVAILABLE}
[junit4] 2> 126896 INFO (jetty-closer-2137-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 126897 INFO (TEST-TestContainerReqHandler.testSetClusterReqHandler-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 127111 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/aliases.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/clusterprops.json
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 32 /solr/live_nodes
[junit4] 2> 9 /solr/collections
[junit4] 2> 8 /solr/overseer/queue
[junit4] 2>
[junit4] 2> 127789 INFO (TEST-TestContainerReqHandler.testSetClusterReqHandler-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:52463
[junit4] 2> 127789 INFO (TEST-TestContainerReqHandler.testSetClusterReqHandler-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52463
[junit4] 2> 127789 INFO (TEST-TestContainerReqHandler.testSetClusterReqHandler-seed#[E70E8D55030F58A2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52463
[junit4] 2> 127790 INFO (TEST-TestContainerReqHandler.testSetClusterReqHandler-seed#[E70E8D55030F58A2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSetClusterReqHandler
[junit4] OK 3.82s J0 | TestContainerReqHandler.testSetClusterReqHandler
[junit4] 2> 127791 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 127791 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:52463
[junit4] 2> 127791 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52463
[junit4] 2> 127791 INFO (SUITE-TestContainerReqHandler-seed#[E70E8D55030F58A2]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52463
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerReqHandler_E70E8D55030F58A2-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {_root_=Lucene50(blocksize=128), id=PostingsFormat(name=Asserting), desc_s=PostingsFormat(name=Asserting)}, docValues:{_version_=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1860, maxMBSortInHeap=5.910625275114971, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7efd0c95), locale=rm-CH, timezone=AET
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=3,threads=1,free=50226768,total=185581568
[junit4] 2> NOTE: All tests run in this JVM: [TestContainerReqHandler, TestContainerReqHandler, TestContainerReqHandler]
[junit4] Completed [5/5 (1!)] on J0 in 30.85s, 6 tests, 1 failure <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/common-build.xml:1572: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/common-build.xml:1099: There were test failures: 5 suites, 30 tests, 1 failure [seed: E70E8D55030F58A2]
Total time: 2 minutes 11 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 1/5 failed: org.apache.solr.handler.TestContainerReqHandler
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=fce0a5d45b16cba8e758cda534bae0a1d89d8ed1, workspace=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX
[WARNINGS] Computing warning deltas based on reference build #330
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2