You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2019/04/25 09:03:15 UTC
[JENKINS] Lucene-Solr-NightlyTests-8.x - Build # 81 - Still
Unstable
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-8.x/81/
3 tests failed.
FAILED: org.apache.solr.cloud.OverseerTest.testOverseerFailure
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([E8A6B7DC858DB358]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.OverseerTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([E8A6B7DC858DB358]:0)
FAILED: org.apache.solr.cloud.api.collections.CustomCollectionTest.testRouteFieldForImplicitRouter
Error Message:
expected:<3> but was:<1>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<1>
at __randomizedtesting.SeedInfo.seed([E8A6B7DC858DB358:BDF65F4E29747CA8]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.cloud.api.collections.CustomCollectionTest.testRouteFieldForImplicitRouter(CustomCollectionTest.java:145)
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 15300 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.CustomCollectionTest
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/init-core-data-001
[junit4] 2> 6610029 INFO (SUITE-CustomCollectionTest-seed#[E8A6B7DC858DB358]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001
[junit4] 2> 6610050 INFO (SUITE-CustomCollectionTest-seed#[E8A6B7DC858DB358]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 6610050 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 6610050 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 6610155 INFO (SUITE-CustomCollectionTest-seed#[E8A6B7DC858DB358]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:46098
[junit4] 2> 6610155 INFO (SUITE-CustomCollectionTest-seed#[E8A6B7DC858DB358]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46098
[junit4] 2> 6610155 INFO (SUITE-CustomCollectionTest-seed#[E8A6B7DC858DB358]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46098
[junit4] 2> 6610177 INFO (zkConnectionManagerCallback-22718-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6610191 INFO (zkConnectionManagerCallback-22720-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6610200 INFO (zkConnectionManagerCallback-22722-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6610202 WARN (jetty-launcher-22723-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 6610202 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 6610202 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 6610202 INFO (jetty-launcher-22723-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 6610202 WARN (jetty-launcher-22723-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 6610203 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 6610203 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 6610203 INFO (jetty-launcher-22723-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 6610203 WARN (jetty-launcher-22723-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 6610203 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 6610203 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 6610203 INFO (jetty-launcher-22723-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 6610204 WARN (jetty-launcher-22723-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 6610204 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 6610204 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 6610204 INFO (jetty-launcher-22723-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 6610205 INFO (jetty-launcher-22723-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 6610205 INFO (jetty-launcher-22723-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 6610205 INFO (jetty-launcher-22723-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 6610205 INFO (jetty-launcher-22723-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a6b3f4f{/solr,null,AVAILABLE}
[junit4] 2> 6610206 INFO (jetty-launcher-22723-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 6610206 INFO (jetty-launcher-22723-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 6610206 INFO (jetty-launcher-22723-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 6610206 INFO (jetty-launcher-22723-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@399a7959{/solr,null,AVAILABLE}
[junit4] 2> 6610207 INFO (jetty-launcher-22723-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 6610207 INFO (jetty-launcher-22723-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 6610207 INFO (jetty-launcher-22723-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 6610207 INFO (jetty-launcher-22723-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@53c7cdf9{/solr,null,AVAILABLE}
[junit4] 2> 6610277 INFO (jetty-launcher-22723-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 6610277 INFO (jetty-launcher-22723-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 6610277 INFO (jetty-launcher-22723-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 6610277 INFO (jetty-launcher-22723-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4fb97724{/solr,null,AVAILABLE}
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@208f3aa6{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:41626}
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.e.j.s.Server Started @6610553ms
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41626}
[junit4] 2> 6610281 ERROR (jetty-launcher-22723-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 6610281 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-25T07:36:27.107Z
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@50871e28{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36301}
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.e.j.s.Server Started @6610615ms
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36301}
[junit4] 2> 6610343 ERROR (jetty-launcher-22723-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 6610343 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-25T07:36:27.169Z
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4db9f6c5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:41036}
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.e.j.s.Server Started @6610617ms
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41036}
[junit4] 2> 6610345 ERROR (jetty-launcher-22723-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 6610345 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-25T07:36:27.171Z
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@54a2a952{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37224}
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.e.j.s.Server Started @6610630ms
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37224}
[junit4] 2> 6610358 INFO (zkConnectionManagerCallback-22727-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6610358 ERROR (jetty-launcher-22723-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 6610358 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-25T07:36:27.184Z
[junit4] 2> 6610359 INFO (zkConnectionManagerCallback-22729-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6610365 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 6610365 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 6610410 INFO (zkConnectionManagerCallback-22725-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6610410 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 6610491 INFO (zkConnectionManagerCallback-22731-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6610511 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 6610730 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 6610937 WARN (jetty-launcher-22723-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@783e977a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6610974 WARN (jetty-launcher-22723-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@35acc7a4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6610975 INFO (jetty-launcher-22723-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46098/solr
[junit4] 2> 6611017 INFO (zkConnectionManagerCallback-22741-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6611074 INFO (zkConnectionManagerCallback-22743-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6611525 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 6611557 WARN (jetty-launcher-22723-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@756ddcc3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6611573 WARN (jetty-launcher-22723-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4b392e29[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6611574 INFO (jetty-launcher-22723-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46098/solr
[junit4] 2> 6611579 INFO (zkConnectionManagerCallback-22751-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6611630 INFO (zkConnectionManagerCallback-22753-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6611633 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41626_solr
[junit4] 2> 6611633 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.Overseer Overseer (id=74581725386506248-127.0.0.1:41626_solr-n_0000000000) starting
[junit4] 2> 6611703 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41036_solr
[junit4] 2> 6611738 INFO (zkConnectionManagerCallback-22760-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6611740 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6611740 INFO (zkCallback-22742-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6611740 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46098/solr ready
[junit4] 2> 6611741 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41626_solr
[junit4] 2> 6611747 INFO (zkCallback-22752-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6611783 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41626_solr as DOWN
[junit4] 2> 6611872 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 6611872 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41626_solr
[junit4] 2> 6611922 INFO (zkCallback-22742-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 6611997 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 6612001 INFO (zkConnectionManagerCallback-22765-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6612010 INFO (zkCallback-22752-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 6612011 WARN (jetty-launcher-22723-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5c041c8b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6612013 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 6612018 INFO (zkCallback-22759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 6612162 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46098/solr ready
[junit4] 2> 6612163 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 6612164 WARN (jetty-launcher-22723-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6398ae[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6612165 INFO (jetty-launcher-22723-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46098/solr
[junit4] 2> 6612200 INFO (zkConnectionManagerCallback-22772-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6612266 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x108f7af2066000d, likely client has closed socket
[junit4] 2> 6612277 INFO (zkConnectionManagerCallback-22774-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6612308 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 6612364 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:36301_solr as DOWN
[junit4] 2> 6612366 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 6612366 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36301_solr
[junit4] 2> 6612379 INFO (zkCallback-22742-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 6612379 INFO (zkCallback-22752-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 6612391 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 6612391 INFO (zkCallback-22759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 6612412 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41036.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612448 INFO (zkCallback-22773-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 6612451 INFO (zkCallback-22764-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 6612485 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41036.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612485 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41036.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612486 INFO (jetty-launcher-22723-thread-2) [n:127.0.0.1:41036_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/.
[junit4] 2> 6612502 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41626.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612520 INFO (zkConnectionManagerCallback-22782-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6612525 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 6612541 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 6612542 WARN (jetty-launcher-22723-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2f9ed3ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6612542 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46098/solr ready
[junit4] 2> 6612543 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 6612581 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41626.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612581 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41626.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612582 INFO (jetty-launcher-22723-thread-3) [n:127.0.0.1:41626_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/.
[junit4] 2> 6612594 WARN (jetty-launcher-22723-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@33d7ae00[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6612595 INFO (jetty-launcher-22723-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46098/solr
[junit4] 2> 6612604 INFO (zkConnectionManagerCallback-22789-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6612639 INFO (zkConnectionManagerCallback-22791-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6612672 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36301.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612699 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 6612716 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37224_solr as DOWN
[junit4] 2> 6612717 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 6612717 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37224_solr
[junit4] 2> 6612731 INFO (zkCallback-22764-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 6612731 INFO (zkCallback-22773-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 6612731 INFO (zkCallback-22752-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 6612731 INFO (zkCallback-22759-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 6612731 INFO (zkCallback-22742-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 6612736 WARN (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register gauge
[junit4] 2> => javax.management.InstanceNotFoundException: solr_36301:dom1=solr,dom2=jvm,name0=system,name=properties
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
[junit4] 2> javax.management.InstanceNotFoundException: solr_36301:dom1=solr,dom2=jvm,name0=system,name=properties
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095) ~[?:1.8.0_191]
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427) ~[?:1.8.0_191]
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) ~[?:1.8.0_191]
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) ~[?:1.8.0_191]
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:531) ~[java/:?]
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:567) ~[java/:?]
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:742) ~[java/:?]
[junit4] 2> at java.util.HashMap.forEach(HashMap.java:1289) ~[?:1.8.0_191]
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:732) ~[java/:?]
[junit4] 2> at org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:109) ~[java/:?]
[junit4] 2> at org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70) ~[java/:?]
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:916) ~[java/:?]
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:843) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:655) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:255) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:175) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:136) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.lambda$initialize$0(ServletHandler.java:750) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at java.util.Spliterators$ArraySpliterator.forEachRemaining(Spliterators.java:948) ~[?:1.8.0_191]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:742) ~[?:1.8.0_191]
[junit4] 2> at java.util.stream.Streams$ConcatSpliterator.forEachRemaining(Streams.java:742) ~[?:1.8.0_191]
[junit4] 2> at java.util.stream.ReferencePipeline$Head.forEach(ReferencePipeline.java:580) ~[?:1.8.0_191]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:744) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1449) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1513) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1158) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:995) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:467) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:384) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179) ~[jetty-util-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69) ~[jetty-util-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.retryOnPortBindFailure(JettySolrRunner.java:558) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:497) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:465) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:467) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:300) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_191]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[?:1.8.0_191]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[?:1.8.0_191]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_191]
[junit4] 2> 6612738 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36301.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612738 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36301.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6612774 INFO (zkCallback-22781-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 6612848 INFO (jetty-launcher-22723-thread-1) [n:127.0.0.1:36301_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node1/.
[junit4] 2> 6612861 INFO (zkCallback-22790-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 6612986 INFO (zkConnectionManagerCallback-22798-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6612994 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 6613003 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46098/solr ready
[junit4] 2> 6613005 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 6613086 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37224.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6613122 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37224.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6613122 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37224.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6613123 INFO (jetty-launcher-22723-thread-4) [n:127.0.0.1:37224_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/.
[junit4] 2> 6613343 INFO (zkConnectionManagerCallback-22804-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6613344 INFO (SUITE-CustomCollectionTest-seed#[E8A6B7DC858DB358]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 6613345 INFO (SUITE-CustomCollectionTest-seed#[E8A6B7DC858DB358]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46098/solr ready
[junit4] 2> 6613911 INFO (TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[E8A6B7DC858DB358]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 6613916 INFO (qtp2072761865-108359) [n:127.0.0.1:36301_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=a,b,c&collection.configName=conf&maxShardsPerNode=6&name=implicitcoll&router.name=implicit&nrtReplicas=5&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 6613998 INFO (OverseerThreadFactory-13041-thread-1-processing-n:127.0.0.1:41626_solr) [n:127.0.0.1:41626_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection implicitcoll
[junit4] 2> 6614134 WARN (OverseerThreadFactory-13041-thread-1-processing-n:127.0.0.1:41626_solr) [n:127.0.0.1:41626_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 5 on collection implicitcoll is higher than the number of Solr instances currently live or live and part of your createNodeSet(4). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 6614137 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"implicitcoll_a_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41036/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614173 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"implicitcoll_a_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41626/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614284 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"implicitcoll_a_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37224/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614286 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"implicitcoll_a_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36301/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614296 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"implicitcoll_a_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41036/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614312 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"implicitcoll_b_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41626/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614317 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"implicitcoll_b_replica_n9",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37224/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614318 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"implicitcoll_b_replica_n10",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36301/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614319 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"implicitcoll_b_replica_n11",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41036/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614361 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"implicitcoll_b_replica_n12",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41626/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614363 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"implicitcoll_c_replica_n13",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37224/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614377 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"implicitcoll_c_replica_n14",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36301/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614379 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"implicitcoll_c_replica_n15",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41036/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614393 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"implicitcoll_c_replica_n18",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41626/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614395 INFO (OverseerStateUpdate-74581725386506248-127.0.0.1:41626_solr-n_0000000000) [n:127.0.0.1:41626_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"implicitcoll",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"implicitcoll_c_replica_n20",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37224/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 6614559 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr x:implicitcoll_a_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node16&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n5&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614577 INFO (qtp2072761865-108361) [n:127.0.0.1:36301_solr x:implicitcoll_a_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node17&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n6&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614583 INFO (qtp2072761865-108360) [n:127.0.0.1:36301_solr x:implicitcoll_c_replica_n14] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node27&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n14&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614583 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr x:implicitcoll_b_replica_n10] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node23&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n10&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614588 INFO (qtp473095921-108376) [n:127.0.0.1:41626_solr x:implicitcoll_b_replica_n12] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node25&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n12&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614588 INFO (qtp473095921-108377) [n:127.0.0.1:41626_solr x:implicitcoll_a_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n2&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614599 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr x:implicitcoll_c_replica_n15] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node28&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n15&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614600 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr x:implicitcoll_c_replica_n15] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 6614600 INFO (qtp2088230684-108370) [n:127.0.0.1:37224_solr x:implicitcoll_c_replica_n20] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node30&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n20&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614601 INFO (qtp473095921-108378) [n:127.0.0.1:41626_solr x:implicitcoll_b_replica_n8] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node21&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n8&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614601 INFO (qtp2088230684-108371) [n:127.0.0.1:37224_solr x:implicitcoll_b_replica_n9] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node22&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n9&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614602 INFO (qtp2088230684-108367) [n:127.0.0.1:37224_solr x:implicitcoll_c_replica_n13] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node26&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n13&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614603 INFO (qtp473095921-108375) [n:127.0.0.1:41626_solr x:implicitcoll_c_replica_n18] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node29&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n18&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614603 INFO (qtp642639461-108351) [n:127.0.0.1:41036_solr x:implicitcoll_a_replica_n7] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node19&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n7&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614604 INFO (qtp642639461-108352) [n:127.0.0.1:41036_solr x:implicitcoll_a_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n1&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6614616 INFO (qtp642639461-108353) [n:127.0.0.1:41036_solr x:implicitcoll_b_replica_n11] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node24&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n11&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 6615582 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6615629 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.s.IndexSchema [implicitcoll_a_replica_n5] Schema name=cloud-dynamic
[junit4] 2> 6615864 INFO (qtp2072761865-108361) [n:127.0.0.1:36301_solr c:implicitcoll s:a r:core_node17 x:implicitcoll_a_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6615895 INFO (qtp2072761865-108360) [n:127.0.0.1:36301_solr c:implicitcoll s:c r:core_node27 x:implicitcoll_c_replica_n14] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6615896 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6615913 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6615913 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n5' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6615914 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37224.solr.core.implicitcoll.a.replica_n5' (registry 'solr.core.implicitcoll.a.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6615914 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.c.SolrCore [[implicitcoll_a_replica_n5] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/implicitcoll_a_replica_n5], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/./implicitcoll_a_replica_n5/data/]
[junit4] 2> 6616139 INFO (qtp2088230684-108371) [n:127.0.0.1:37224_solr c:implicitcoll s:b r:core_node22 x:implicitcoll_b_replica_n9] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616155 INFO (qtp2088230684-108367) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node26 x:implicitcoll_c_replica_n13] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616441 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.s.IndexSchema [implicitcoll_b_replica_n10] Schema name=cloud-dynamic
[junit4] 2> 6616479 INFO (qtp2072761865-108361) [n:127.0.0.1:36301_solr c:implicitcoll s:a r:core_node17 x:implicitcoll_a_replica_n6] o.a.s.s.IndexSchema [implicitcoll_a_replica_n6] Schema name=cloud-dynamic
[junit4] 2> 6616480 INFO (qtp2088230684-108370) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node30 x:implicitcoll_c_replica_n20] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616497 INFO (qtp2088230684-108371) [n:127.0.0.1:37224_solr c:implicitcoll s:b r:core_node22 x:implicitcoll_b_replica_n9] o.a.s.s.IndexSchema [implicitcoll_b_replica_n9] Schema name=cloud-dynamic
[junit4] 2> 6616499 INFO (qtp642639461-108353) [n:127.0.0.1:41036_solr c:implicitcoll s:b r:core_node24 x:implicitcoll_b_replica_n11] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616512 INFO (qtp473095921-108378) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node21 x:implicitcoll_b_replica_n8] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616690 INFO (qtp2072761865-108360) [n:127.0.0.1:36301_solr c:implicitcoll s:c r:core_node27 x:implicitcoll_c_replica_n14] o.a.s.s.IndexSchema [implicitcoll_c_replica_n14] Schema name=cloud-dynamic
[junit4] 2> 6616707 INFO (qtp473095921-108377) [n:127.0.0.1:41626_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616844 INFO (qtp473095921-108376) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node25 x:implicitcoll_b_replica_n12] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616892 INFO (qtp473095921-108378) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node21 x:implicitcoll_b_replica_n8] o.a.s.s.IndexSchema [implicitcoll_b_replica_n8] Schema name=cloud-dynamic
[junit4] 2> 6616902 INFO (qtp473095921-108375) [n:127.0.0.1:41626_solr c:implicitcoll s:c r:core_node29 x:implicitcoll_c_replica_n18] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6616931 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr c:implicitcoll s:c r:core_node28 x:implicitcoll_c_replica_n15] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6617089 INFO (qtp2088230684-108367) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node26 x:implicitcoll_c_replica_n13] o.a.s.s.IndexSchema [implicitcoll_c_replica_n13] Schema name=cloud-dynamic
[junit4] 2> 6617097 INFO (qtp642639461-108353) [n:127.0.0.1:41036_solr c:implicitcoll s:b r:core_node24 x:implicitcoll_b_replica_n11] o.a.s.s.IndexSchema [implicitcoll_b_replica_n11] Schema name=cloud-dynamic
[junit4] 2> 6617100 INFO (qtp473095921-108377) [n:127.0.0.1:41626_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n2] o.a.s.s.IndexSchema [implicitcoll_a_replica_n2] Schema name=cloud-dynamic
[junit4] 2> 6617109 INFO (qtp2088230684-108370) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node30 x:implicitcoll_c_replica_n20] o.a.s.s.IndexSchema [implicitcoll_c_replica_n20] Schema name=cloud-dynamic
[junit4] 2> 6617223 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6617223 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n10' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6617245 INFO (qtp642639461-108352) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6617251 INFO (qtp642639461-108351) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node19 x:implicitcoll_a_replica_n7] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 6617296 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr c:implicitcoll s:c r:core_node28 x:implicitcoll_c_replica_n15] o.a.s.s.IndexSchema [implicitcoll_c_replica_n15] Schema name=cloud-dynamic
[junit4] 2> 6617296 INFO (qtp473095921-108376) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node25 x:implicitcoll_b_replica_n12] o.a.s.s.IndexSchema [implicitcoll_b_replica_n12] Schema name=cloud-dynamic
[junit4] 2> 6617301 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36301.solr.core.implicitcoll.b.replica_n10' (registry 'solr.core.implicitcoll.b.replica_n10') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6617301 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.c.SolrCore [[implicitcoll_b_replica_n10] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node1/implicitcoll_b_replica_n10], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node1/./implicitcoll_b_replica_n10/data/]
[junit4] 2> 6617802 INFO (qtp2072761865-108361) [n:127.0.0.1:36301_solr c:implicitcoll s:a r:core_node17 x:implicitcoll_a_replica_n6] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6617802 INFO (qtp2072761865-108361) [n:127.0.0.1:36301_solr c:implicitcoll s:a r:core_node17 x:implicitcoll_a_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n6' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6617803 INFO (qtp2072761865-108361) [n:127.0.0.1:36301_solr c:implicitcoll s:a r:core_node17 x:implicitcoll_a_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36301.solr.core.implicitcoll.a.replica_n6' (registry 'solr.core.implicitcoll.a.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6617803 INFO (qtp2072761865-108361) [n:127.0.0.1:36301_solr c:implicitcoll s:a r:core_node17 x:implicitcoll_a_replica_n6] o.a.s.c.SolrCore [[implicitcoll_a_replica_n6] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node1/implicitcoll_a_replica_n6], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node1/./implicitcoll_a_replica_n6/data/]
[junit4] 2> 6617832 INFO (qtp473095921-108375) [n:127.0.0.1:41626_solr c:implicitcoll s:c r:core_node29 x:implicitcoll_c_replica_n18] o.a.s.s.IndexSchema [implicitcoll_c_replica_n18] Schema name=cloud-dynamic
[junit4] 2> 6617834 INFO (qtp473095921-108378) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node21 x:implicitcoll_b_replica_n8] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6617834 INFO (qtp473095921-108378) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node21 x:implicitcoll_b_replica_n8] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n8' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6617835 INFO (qtp473095921-108378) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node21 x:implicitcoll_b_replica_n8] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41626.solr.core.implicitcoll.b.replica_n8' (registry 'solr.core.implicitcoll.b.replica_n8') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6617835 INFO (qtp473095921-108378) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node21 x:implicitcoll_b_replica_n8] o.a.s.c.SolrCore [[implicitcoll_b_replica_n8] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/implicitcoll_b_replica_n8], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/./implicitcoll_b_replica_n8/data/]
[junit4] 2> 6618078 INFO (qtp2088230684-108371) [n:127.0.0.1:37224_solr c:implicitcoll s:b r:core_node22 x:implicitcoll_b_replica_n9] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618078 INFO (qtp2088230684-108371) [n:127.0.0.1:37224_solr c:implicitcoll s:b r:core_node22 x:implicitcoll_b_replica_n9] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n9' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618078 INFO (qtp642639461-108351) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node19 x:implicitcoll_a_replica_n7] o.a.s.s.IndexSchema [implicitcoll_a_replica_n7] Schema name=cloud-dynamic
[junit4] 2> 6618078 INFO (qtp2088230684-108371) [n:127.0.0.1:37224_solr c:implicitcoll s:b r:core_node22 x:implicitcoll_b_replica_n9] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37224.solr.core.implicitcoll.b.replica_n9' (registry 'solr.core.implicitcoll.b.replica_n9') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618079 INFO (qtp2088230684-108371) [n:127.0.0.1:37224_solr c:implicitcoll s:b r:core_node22 x:implicitcoll_b_replica_n9] o.a.s.c.SolrCore [[implicitcoll_b_replica_n9] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/implicitcoll_b_replica_n9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/./implicitcoll_b_replica_n9/data/]
[junit4] 2> 6618184 INFO (qtp642639461-108353) [n:127.0.0.1:41036_solr c:implicitcoll s:b r:core_node24 x:implicitcoll_b_replica_n11] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618184 INFO (qtp642639461-108353) [n:127.0.0.1:41036_solr c:implicitcoll s:b r:core_node24 x:implicitcoll_b_replica_n11] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n11' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618184 INFO (qtp642639461-108353) [n:127.0.0.1:41036_solr c:implicitcoll s:b r:core_node24 x:implicitcoll_b_replica_n11] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41036.solr.core.implicitcoll.b.replica_n11' (registry 'solr.core.implicitcoll.b.replica_n11') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618184 INFO (qtp642639461-108353) [n:127.0.0.1:41036_solr c:implicitcoll s:b r:core_node24 x:implicitcoll_b_replica_n11] o.a.s.c.SolrCore [[implicitcoll_b_replica_n11] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/implicitcoll_b_replica_n11], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/./implicitcoll_b_replica_n11/data/]
[junit4] 2> 6618290 INFO (qtp642639461-108352) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n1] o.a.s.s.IndexSchema [implicitcoll_a_replica_n1] Schema name=cloud-dynamic
[junit4] 2> 6618327 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr c:implicitcoll s:c r:core_node28 x:implicitcoll_c_replica_n15] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618327 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr c:implicitcoll s:c r:core_node28 x:implicitcoll_c_replica_n15] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n15' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618328 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr c:implicitcoll s:c r:core_node28 x:implicitcoll_c_replica_n15] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41036.solr.core.implicitcoll.c.replica_n15' (registry 'solr.core.implicitcoll.c.replica_n15') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618328 INFO (qtp642639461-108350) [n:127.0.0.1:41036_solr c:implicitcoll s:c r:core_node28 x:implicitcoll_c_replica_n15] o.a.s.c.SolrCore [[implicitcoll_c_replica_n15] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/implicitcoll_c_replica_n15], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/./implicitcoll_c_replica_n15/data/]
[junit4] 2> 6618360 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 6618360 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 6618362 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 6618362 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 6618372 INFO (qtp2072761865-108360) [n:127.0.0.1:36301_solr c:implicitcoll s:c r:core_node27 x:implicitcoll_c_replica_n14] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618372 INFO (qtp2072761865-108360) [n:127.0.0.1:36301_solr c:implicitcoll s:c r:core_node27 x:implicitcoll_c_replica_n14] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n14' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618372 INFO (qtp2072761865-108360) [n:127.0.0.1:36301_solr c:implicitcoll s:c r:core_node27 x:implicitcoll_c_replica_n14] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36301.solr.core.implicitcoll.c.replica_n14' (registry 'solr.core.implicitcoll.c.replica_n14') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618372 INFO (qtp2072761865-108360) [n:127.0.0.1:36301_solr c:implicitcoll s:c r:core_node27 x:implicitcoll_c_replica_n14] o.a.s.c.SolrCore [[implicitcoll_c_replica_n14] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node1/implicitcoll_c_replica_n14], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node1/./implicitcoll_c_replica_n14/data/]
[junit4] 2> 6618397 INFO (qtp473095921-108377) [n:127.0.0.1:41626_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618397 INFO (qtp473095921-108377) [n:127.0.0.1:41626_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n2' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618397 INFO (qtp473095921-108377) [n:127.0.0.1:41626_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41626.solr.core.implicitcoll.a.replica_n2' (registry 'solr.core.implicitcoll.a.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618397 INFO (qtp473095921-108377) [n:127.0.0.1:41626_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n2] o.a.s.c.SolrCore [[implicitcoll_a_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/implicitcoll_a_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/./implicitcoll_a_replica_n2/data/]
[junit4] 2> 6618409 INFO (qtp2088230684-108367) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node26 x:implicitcoll_c_replica_n13] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618409 INFO (qtp2088230684-108367) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node26 x:implicitcoll_c_replica_n13] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n13' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618409 INFO (qtp2088230684-108367) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node26 x:implicitcoll_c_replica_n13] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37224.solr.core.implicitcoll.c.replica_n13' (registry 'solr.core.implicitcoll.c.replica_n13') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618409 INFO (qtp2088230684-108367) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node26 x:implicitcoll_c_replica_n13] o.a.s.c.SolrCore [[implicitcoll_c_replica_n13] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/implicitcoll_c_replica_n13], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/./implicitcoll_c_replica_n13/data/]
[junit4] 2> 6618412 INFO (qtp473095921-108376) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node25 x:implicitcoll_b_replica_n12] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618412 INFO (qtp473095921-108376) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node25 x:implicitcoll_b_replica_n12] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n12' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618412 INFO (qtp473095921-108376) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node25 x:implicitcoll_b_replica_n12] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41626.solr.core.implicitcoll.b.replica_n12' (registry 'solr.core.implicitcoll.b.replica_n12') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618412 INFO (qtp473095921-108376) [n:127.0.0.1:41626_solr c:implicitcoll s:b r:core_node25 x:implicitcoll_b_replica_n12] o.a.s.c.SolrCore [[implicitcoll_b_replica_n12] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/implicitcoll_b_replica_n12], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/./implicitcoll_b_replica_n12/data/]
[junit4] 2> 6618762 INFO (qtp642639461-108351) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node19 x:implicitcoll_a_replica_n7] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6618762 INFO (qtp642639461-108351) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node19 x:implicitcoll_a_replica_n7] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n7' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6618763 INFO (qtp642639461-108351) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node19 x:implicitcoll_a_replica_n7] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41036.solr.core.implicitcoll.a.replica_n7' (registry 'solr.core.implicitcoll.a.replica_n7') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6618763 INFO (qtp642639461-108351) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node19 x:implicitcoll_a_replica_n7] o.a.s.c.SolrCore [[implicitcoll_a_replica_n7] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/implicitcoll_a_replica_n7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/./implicitcoll_a_replica_n7/data/]
[junit4] 2> 6618776 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.s.SolrIndexSearcher Opening [Searcher@56e281da[implicitcoll_a_replica_n5] main]
[junit4] 2> 6618777 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 6618777 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 6618778 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 6618778 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1631770688203259904
[junit4] 2> 6618833 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.c.ZkShardTerms Successful update of terms at /collections/implicitcoll/terms/a to Terms{values={core_node16=0}, version=0}
[junit4] 2> 6618833 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/implicitcoll/leaders/a
[junit4] 2> 6618836 INFO (qtp2088230684-108368) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard a: total=5 found=1 timeoutin=9999ms
[junit4] 2> 6619033 INFO (qtp473095921-108375) [n:127.0.0.1:41626_solr c:implicitcoll s:c r:core_node29 x:implicitcoll_c_replica_n18] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6619033 INFO (qtp473095921-108375) [n:127.0.0.1:41626_solr c:implicitcoll s:c r:core_node29 x:implicitcoll_c_replica_n18] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n18' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6619033 INFO (qtp473095921-108375) [n:127.0.0.1:41626_solr c:implicitcoll s:c r:core_node29 x:implicitcoll_c_replica_n18] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41626.solr.core.implicitcoll.c.replica_n18' (registry 'solr.core.implicitcoll.c.replica_n18') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6619033 INFO (qtp473095921-108375) [n:127.0.0.1:41626_solr c:implicitcoll s:c r:core_node29 x:implicitcoll_c_replica_n18] o.a.s.c.SolrCore [[implicitcoll_c_replica_n18] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/implicitcoll_c_replica_n18], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node3/./implicitcoll_c_replica_n18/data/]
[junit4] 2> 6619035 INFO (qtp2088230684-108370) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node30 x:implicitcoll_c_replica_n20] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6619035 INFO (qtp2088230684-108370) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node30 x:implicitcoll_c_replica_n20] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n20' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6619035 INFO (qtp2088230684-108370) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node30 x:implicitcoll_c_replica_n20] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37224.solr.core.implicitcoll.c.replica_n20' (registry 'solr.core.implicitcoll.c.replica_n20') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6619035 INFO (qtp2088230684-108370) [n:127.0.0.1:37224_solr c:implicitcoll s:c r:core_node30 x:implicitcoll_c_replica_n20] o.a.s.c.SolrCore [[implicitcoll_c_replica_n20] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/implicitcoll_c_replica_n20], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node4/./implicitcoll_c_replica_n20/data/]
[junit4] 2> 6619070 INFO (qtp642639461-108352) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 6619070 INFO (qtp642639461-108352) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n1' using configuration from collection implicitcoll, trusted=true
[junit4] 2> 6619070 INFO (qtp642639461-108352) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41036.solr.core.implicitcoll.a.replica_n1' (registry 'solr.core.implicitcoll.a.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a59b43a
[junit4] 2> 6619070 INFO (qtp642639461-108352) [n:127.0.0.1:41036_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n1] o.a.s.c.SolrCore [[implicitcoll_a_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/implicitcoll_a_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_E8A6B7DC858DB358-001/tempDir-001/node2/./implicitcoll_a_replica_n1/data/]
[junit4] 2> 6619123 INFO (searcherExecutor-13064-thread-1-processing-n:127.0.0.1:37224_solr x:implicitcoll_a_replica_n5 c:implicitcoll s:a r:core_node16) [n:127.0.0.1:37224_solr c:implicitcoll s:a r:core_node16 x:implicitcoll_a_replica_n5] o.a.s.c.SolrCore [implicitcoll_a_replica_n5] Registered new searcher Searcher@56e281da[implicitcoll_a_replica_n5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 6622543 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 6622543 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 6622609 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 6622609 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 6622793 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.s.SolrIndexSearcher Opening [Searcher@6712e7a0[implicitcoll_b_replica_n10] main]
[junit4] 2> 6622794 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 6622795 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 6622817 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 6622817 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1631770692438458368
[junit4] 2> 6622850 INFO (qtp2072761865-108358) [n:127.0.0.1:36301_solr c:implicitcoll s:b r:core_node23 x:implicitcoll_b_replica_n10
[...truncated too long message...]
fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/null2126929494
[copy] Copying 239 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/null2126929494
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/null2126929494
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: #;working@lucene2-us-west.apache.org
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.54 in public
[ivy:cachepath] found com.jcraft#jzlib;1.1.1 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] found org.bouncycastle#bcpg-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcprov-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcpkix-jdk15on;1.60 in public
[ivy:cachepath] found org.slf4j#slf4j-nop;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 94ms :: artifacts dl 13ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 9 | 0 | 0 | 0 || 9 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 635 minutes 32 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
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:1835)
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:3088)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
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:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
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:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
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:1835)
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/**,**/hs_err_pid*". Configuration error?
Recording test results
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)