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 2018/09/09 08:26:15 UTC
[JENKINS] Lucene-Solr-Tests-7.x - Build # 866 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/866/
2 tests failed.
FAILED: org.apache.solr.cloud.api.collections.CustomCollectionTest.testCustomCollectionsAPI
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([97126369971BF459:FDF3ED02AA814221]:0)
at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:280)
at org.apache.solr.cloud.api.collections.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:106)
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:1742)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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)
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimLargeCluster.testSearchRate
Error Message:
{}
Stack Trace:
java.lang.AssertionError: {}
at __randomizedtesting.SeedInfo.seed([97126369971BF459:CA5A7DE058DD5216]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.cloud.autoscaling.sim.TestSimLargeCluster.testSearchRate(TestSimLargeCluster.java:695)
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:1742)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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 12685 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.CustomCollectionTest
[junit4] 2> 443960 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/init-core-data-001
[junit4] 2> 443961 WARN (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 443961 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 443962 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 443963 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001
[junit4] 2> 443963 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 443963 INFO (Thread-653) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 443963 INFO (Thread-653) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 444061 ERROR (Thread-653) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 444065 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:41422
[junit4] 2> 444196 INFO (zkConnectionManagerCallback-851-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 444249 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 444249 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 444261 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 444270 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 444305 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 444305 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 444305 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 444306 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f29cfa6{/solr,null,AVAILABLE}
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a6a09ab{HTTP/1.1,[http/1.1]}{127.0.0.1:33904}
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.Server Started @444519ms
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33904}
[junit4] 2> 444308 ERROR (jetty-launcher-848-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 444308 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T06:21:51.763Z
[junit4] 2> 444379 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 444379 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 444379 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 444379 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e1468ee{/solr,null,AVAILABLE}
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d924760{HTTP/1.1,[http/1.1]}{127.0.0.1:40679}
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.Server Started @444592ms
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40679}
[junit4] 2> 444380 ERROR (jetty-launcher-848-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 444380 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T06:21:51.835Z
[junit4] 2> 444432 INFO (zkConnectionManagerCallback-853-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 444432 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 444464 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 444464 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 444464 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 444465 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@78e9540b{/solr,null,AVAILABLE}
[junit4] 2> 444465 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1e471590{HTTP/1.1,[http/1.1]}{127.0.0.1:40737}
[junit4] 2> 444465 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.Server Started @444677ms
[junit4] 2> 444465 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40737}
[junit4] 2> 444466 ERROR (jetty-launcher-848-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 444466 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 444466 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 444466 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 444466 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 444466 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T06:21:51.921Z
[junit4] 2> 444562 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 444562 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 444562 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 444562 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@49145e30{/solr,null,AVAILABLE}
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@552047{HTTP/1.1,[http/1.1]}{127.0.0.1:43410}
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.Server Started @444774ms
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43410}
[junit4] 2> 444563 ERROR (jetty-launcher-848-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 444563 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T06:21:52.018Z
[junit4] 2> 444602 INFO (zkConnectionManagerCallback-857-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 444615 INFO (zkConnectionManagerCallback-859-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 444615 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 444639 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 444647 INFO (zkConnectionManagerCallback-855-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 444651 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 445037 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 0x1046140e6380004, likely client has closed socket
[junit4] 2> 445895 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41422/solr
[junit4] 2> 445934 INFO (zkConnectionManagerCallback-863-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 446281 INFO (zkConnectionManagerCallback-865-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 446314 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41422/solr
[junit4] 2> 446371 INFO (zkConnectionManagerCallback-869-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 446519 INFO (zkConnectionManagerCallback-871-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 447128 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41422/solr
[junit4] 2> 447192 INFO (zkConnectionManagerCallback-879-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 447235 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40679_solr
[junit4] 2> 447236 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.c.Overseer Overseer (id=73290425313001480-127.0.0.1:40679_solr-n_0000000000) starting
[junit4] 2> 447449 INFO (zkConnectionManagerCallback-881-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 447469 INFO (zkConnectionManagerCallback-886-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 447474 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41422/solr ready
[junit4] 2> 447475 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:40679_solr
[junit4] 2> 447521 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40679_solr
[junit4] 2> 447639 INFO (zkCallback-885-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 447639 INFO (zkCallback-870-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 447724 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 447908 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 448021 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 448021 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 448023 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:40679_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/.
[junit4] 2> 448143 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 448166 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 448166 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40737_solr
[junit4] 2> 448287 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41422/solr
[junit4] 2> 448297 INFO (zkCallback-870-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 448314 INFO (zkCallback-885-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 448334 INFO (zkConnectionManagerCallback-893-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 448338 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 448346 INFO (zkCallback-880-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 448407 INFO (zkConnectionManagerCallback-895-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 448472 INFO (zkConnectionManagerCallback-900-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 448545 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 448554 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 448569 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 448569 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43410_solr
[junit4] 2> 448607 INFO (zkCallback-885-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 448607 INFO (zkCallback-870-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 448607 INFO (zkCallback-864-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 448618 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41422/solr ready
[junit4] 2> 448622 INFO (zkCallback-880-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 448622 INFO (zkCallback-899-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 448630 INFO (zkCallback-894-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 448642 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 448837 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 448838 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33904_solr
[junit4] 2> 448846 INFO (zkConnectionManagerCallback-908-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 448863 INFO (zkCallback-885-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 448865 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 448865 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41422/solr ready
[junit4] 2> 448878 INFO (zkCallback-864-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 448882 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 448939 INFO (zkCallback-894-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 448951 INFO (zkCallback-880-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 449289 INFO (zkCallback-870-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 449326 INFO (zkCallback-899-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 449363 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43410.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 449402 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 449474 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43410.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 449691 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 449692 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43410.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 449692 INFO (zkConnectionManagerCallback-914-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 449693 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:43410_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node2/.
[junit4] 2> 449712 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 449713 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:40737_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/.
[junit4] 2> 449763 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 449764 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41422/solr ready
[junit4] 2> 449785 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 450021 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33904.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 450161 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33904.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 450162 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33904.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 450163 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:33904_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node4/.
[junit4] 2> 450536 INFO (zkConnectionManagerCallback-917-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 450540 INFO (zkConnectionManagerCallback-922-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 450541 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 450559 INFO (SUITE-CustomCollectionTest-seed#[97126369971BF459]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41422/solr ready
[junit4] 2> 450727 INFO (TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[97126369971BF459]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForImplicitRouter
[junit4] 2> 450728 INFO (TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[97126369971BF459]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 450800 INFO (qtp999718583-3271) [n:127.0.0.1:40679_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=a,b,c,d&collection.configName=conf&maxShardsPerNode=6&router.field=shard_s&name=withShardField&router.name=implicit&nrtReplicas=5&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 450847 INFO (OverseerThreadFactory-1621-thread-1-processing-n:127.0.0.1:40679_solr) [n:127.0.0.1:40679_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection withShardField
[junit4] 2> 450969 WARN (OverseerThreadFactory-1621-thread-1-processing-n:127.0.0.1:40679_solr) [n:127.0.0.1:40679_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 5 on collection withShardField 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> 450989 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40679/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451016 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43410/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451034 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33904/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451072 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40737/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451088 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40679/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451090 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n10",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43410/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451147 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n12",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33904/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451209 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n13",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40737/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451217 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n15",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40679/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451239 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n16",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43410/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451240 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n17",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33904/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451264 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n19",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40737/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451278 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n21",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40679/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451287 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n24",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43410/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451399 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n29",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33904/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451484 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n30",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40737/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451502 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n32",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40679/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451518 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n33",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43410/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451543 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n34",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33904/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451544 INFO (OverseerStateUpdate-73290425313001480-127.0.0.1:40679_solr-n_0000000000) [n:127.0.0.1:40679_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n38",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40737/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 451747 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr x:withShardField_a_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n1&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451748 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr x:withShardField_a_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 451748 INFO (qtp999718583-3267) [n:127.0.0.1:40679_solr x:withShardField_a_replica_n8] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n8&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451766 INFO (qtp1590695156-3294) [n:127.0.0.1:43410_solr x:withShardField_a_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n2&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451808 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr x:withShardField_d_replica_n30] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node35&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n30&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451809 INFO (qtp999718583-3270) [n:127.0.0.1:40679_solr x:withShardField_b_replica_n15] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node22&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n15&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451809 INFO (qtp999718583-3273) [n:127.0.0.1:40679_solr x:withShardField_c_replica_n21] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node27&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n21&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451810 INFO (qtp446064310-3259) [n:127.0.0.1:33904_solr x:withShardField_a_replica_n4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n4&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451811 INFO (qtp446064310-3258) [n:127.0.0.1:33904_solr x:withShardField_b_replica_n12] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node18&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n12&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451823 INFO (qtp446064310-3256) [n:127.0.0.1:33904_solr x:withShardField_c_replica_n17] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node25&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n17&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451825 INFO (qtp1590695156-3276) [n:127.0.0.1:43410_solr x:withShardField_b_replica_n16] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node23&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n16&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451826 INFO (qtp446064310-3262) [n:127.0.0.1:33904_solr x:withShardField_d_replica_n34] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node39&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n34&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451826 INFO (qtp1590695156-3290) [n:127.0.0.1:43410_solr x:withShardField_b_replica_n10] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node14&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n10&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451840 INFO (qtp103329444-3281) [n:127.0.0.1:40737_solr x:withShardField_a_replica_n7] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n7&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451842 INFO (qtp103329444-3280) [n:127.0.0.1:40737_solr x:withShardField_b_replica_n13] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node20&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n13&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451852 INFO (qtp103329444-3284) [n:127.0.0.1:40737_solr x:withShardField_d_replica_n38] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node40&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n38&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451855 INFO (qtp999718583-3428) [n:127.0.0.1:40679_solr x:withShardField_d_replica_n32] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node36&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n32&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451930 INFO (qtp1590695156-3293) [n:127.0.0.1:43410_solr x:withShardField_c_replica_n24] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node28&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n24&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451949 INFO (qtp1590695156-3432) [n:127.0.0.1:43410_solr x:withShardField_d_replica_n33] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node37&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n33&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451950 INFO (qtp446064310-3255) [n:127.0.0.1:33904_solr x:withShardField_c_replica_n29] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node31&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n29&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 451992 INFO (qtp103329444-3434) [n:127.0.0.1:40737_solr x:withShardField_c_replica_n19] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node26&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n19&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 452876 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 452906 INFO (qtp999718583-3267) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node11 x:withShardField_a_replica_n8] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 452932 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.s.IndexSchema [withShardField_d_replica_n30] Schema name=cloud-dynamic
[junit4] 2> 453171 INFO (qtp999718583-3267) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node11 x:withShardField_a_replica_n8] o.a.s.s.IndexSchema [withShardField_a_replica_n8] Schema name=cloud-dynamic
[junit4] 2> 453424 INFO (qtp999718583-3270) [n:127.0.0.1:40679_solr c:withShardField s:b r:core_node22 x:withShardField_b_replica_n15] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453435 INFO (qtp1590695156-3432) [n:127.0.0.1:43410_solr c:withShardField s:d r:core_node37 x:withShardField_d_replica_n33] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453709 INFO (qtp999718583-3267) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node11 x:withShardField_a_replica_n8] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 453709 INFO (qtp999718583-3267) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node11 x:withShardField_a_replica_n8] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_a_replica_n8' using configuration from collection withShardField, trusted=true
[junit4] 2> 453721 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 453721 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_d_replica_n30' using configuration from collection withShardField, trusted=true
[junit4] 2> 453721 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.core.withShardField.d.replica_n30' (registry 'solr.core.withShardField.d.replica_n30') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 453722 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.c.SolrCore [[withShardField_d_replica_n30] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/withShardField_d_replica_n30], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/./withShardField_d_replica_n30/data/]
[junit4] 2> 453726 INFO (qtp999718583-3270) [n:127.0.0.1:40679_solr c:withShardField s:b r:core_node22 x:withShardField_b_replica_n15] o.a.s.s.IndexSchema [withShardField_b_replica_n15] Schema name=cloud-dynamic
[junit4] 2> 453728 INFO (qtp999718583-3428) [n:127.0.0.1:40679_solr c:withShardField s:d r:core_node36 x:withShardField_d_replica_n32] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453729 INFO (qtp1590695156-3432) [n:127.0.0.1:43410_solr c:withShardField s:d r:core_node37 x:withShardField_d_replica_n33] o.a.s.s.IndexSchema [withShardField_d_replica_n33] Schema name=cloud-dynamic
[junit4] 2> 453744 INFO (qtp999718583-3267) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node11 x:withShardField_a_replica_n8] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.core.withShardField.a.replica_n8' (registry 'solr.core.withShardField.a.replica_n8') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 453744 INFO (qtp999718583-3267) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node11 x:withShardField_a_replica_n8] o.a.s.c.SolrCore [[withShardField_a_replica_n8] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/withShardField_a_replica_n8], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/./withShardField_a_replica_n8/data/]
[junit4] 2> 453761 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node3 x:withShardField_a_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453780 INFO (qtp999718583-3273) [n:127.0.0.1:40679_solr c:withShardField s:c r:core_node27 x:withShardField_c_replica_n21] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453797 INFO (qtp103329444-3281) [n:127.0.0.1:40737_solr c:withShardField s:a r:core_node9 x:withShardField_a_replica_n7] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453912 INFO (qtp1590695156-3294) [n:127.0.0.1:43410_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453947 INFO (qtp446064310-3255) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node31 x:withShardField_c_replica_n29] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 453967 INFO (qtp103329444-3284) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node40 x:withShardField_d_replica_n38] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454061 INFO (qtp103329444-3280) [n:127.0.0.1:40737_solr c:withShardField s:b r:core_node20 x:withShardField_b_replica_n13] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454102 INFO (qtp103329444-3281) [n:127.0.0.1:40737_solr c:withShardField s:a r:core_node9 x:withShardField_a_replica_n7] o.a.s.s.IndexSchema [withShardField_a_replica_n7] Schema name=cloud-dynamic
[junit4] 2> 454138 INFO (qtp103329444-3434) [n:127.0.0.1:40737_solr c:withShardField s:c r:core_node26 x:withShardField_c_replica_n19] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454143 INFO (qtp103329444-3284) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node40 x:withShardField_d_replica_n38] o.a.s.s.IndexSchema [withShardField_d_replica_n38] Schema name=cloud-dynamic
[junit4] 2> 454317 INFO (qtp446064310-3262) [n:127.0.0.1:33904_solr c:withShardField s:d r:core_node39 x:withShardField_d_replica_n34] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454334 INFO (qtp999718583-3428) [n:127.0.0.1:40679_solr c:withShardField s:d r:core_node36 x:withShardField_d_replica_n32] o.a.s.s.IndexSchema [withShardField_d_replica_n32] Schema name=cloud-dynamic
[junit4] 2> 454352 INFO (qtp999718583-3270) [n:127.0.0.1:40679_solr c:withShardField s:b r:core_node22 x:withShardField_b_replica_n15] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 454352 INFO (qtp999718583-3270) [n:127.0.0.1:40679_solr c:withShardField s:b r:core_node22 x:withShardField_b_replica_n15] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_b_replica_n15' using configuration from collection withShardField, trusted=true
[junit4] 2> 454373 INFO (qtp1590695156-3276) [n:127.0.0.1:43410_solr c:withShardField s:b r:core_node23 x:withShardField_b_replica_n16] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454381 INFO (qtp1590695156-3293) [n:127.0.0.1:43410_solr c:withShardField s:c r:core_node28 x:withShardField_c_replica_n24] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454384 INFO (qtp103329444-3280) [n:127.0.0.1:40737_solr c:withShardField s:b r:core_node20 x:withShardField_b_replica_n13] o.a.s.s.IndexSchema [withShardField_b_replica_n13] Schema name=cloud-dynamic
[junit4] 2> 454394 INFO (qtp446064310-3255) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node31 x:withShardField_c_replica_n29] o.a.s.s.IndexSchema [withShardField_c_replica_n29] Schema name=cloud-dynamic
[junit4] 2> 454398 INFO (qtp1590695156-3290) [n:127.0.0.1:43410_solr c:withShardField s:b r:core_node14 x:withShardField_b_replica_n10] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454409 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node3 x:withShardField_a_replica_n1] o.a.s.s.IndexSchema [withShardField_a_replica_n1] Schema name=cloud-dynamic
[junit4] 2> 454433 INFO (qtp103329444-3434) [n:127.0.0.1:40737_solr c:withShardField s:c r:core_node26 x:withShardField_c_replica_n19] o.a.s.s.IndexSchema [withShardField_c_replica_n19] Schema name=cloud-dynamic
[junit4] 2> 454587 INFO (qtp1590695156-3294) [n:127.0.0.1:43410_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.s.IndexSchema [withShardField_a_replica_n2] Schema name=cloud-dynamic
[junit4] 2> 454608 INFO (qtp999718583-3270) [n:127.0.0.1:40679_solr c:withShardField s:b r:core_node22 x:withShardField_b_replica_n15] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.core.withShardField.b.replica_n15' (registry 'solr.core.withShardField.b.replica_n15') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 454608 INFO (qtp999718583-3270) [n:127.0.0.1:40679_solr c:withShardField s:b r:core_node22 x:withShardField_b_replica_n15] o.a.s.c.SolrCore [[withShardField_b_replica_n15] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/withShardField_b_replica_n15], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/./withShardField_b_replica_n15/data/]
[junit4] 2> 454621 INFO (qtp999718583-3273) [n:127.0.0.1:40679_solr c:withShardField s:c r:core_node27 x:withShardField_c_replica_n21] o.a.s.s.IndexSchema [withShardField_c_replica_n21] Schema name=cloud-dynamic
[junit4] 2> 454627 INFO (qtp1590695156-3432) [n:127.0.0.1:43410_solr c:withShardField s:d r:core_node37 x:withShardField_d_replica_n33] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 454627 INFO (qtp1590695156-3432) [n:127.0.0.1:43410_solr c:withShardField s:d r:core_node37 x:withShardField_d_replica_n33] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_d_replica_n33' using configuration from collection withShardField, trusted=true
[junit4] 2> 454627 INFO (qtp1590695156-3432) [n:127.0.0.1:43410_solr c:withShardField s:d r:core_node37 x:withShardField_d_replica_n33] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43410.solr.core.withShardField.d.replica_n33' (registry 'solr.core.withShardField.d.replica_n33') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 454627 INFO (qtp1590695156-3432) [n:127.0.0.1:43410_solr c:withShardField s:d r:core_node37 x:withShardField_d_replica_n33] o.a.s.c.SolrCore [[withShardField_d_replica_n33] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node2/withShardField_d_replica_n33], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node2/./withShardField_d_replica_n33/data/]
[junit4] 2> 454657 INFO (qtp446064310-3256) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node25 x:withShardField_c_replica_n17] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454643 INFO (qtp446064310-3259) [n:127.0.0.1:33904_solr c:withShardField s:a r:core_node6 x:withShardField_a_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 454641 INFO (qtp446064310-3258) [n:127.0.0.1:33904_solr c:withShardField s:b r:core_node18 x:withShardField_b_replica_n12] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 455123 INFO (qtp1590695156-3290) [n:127.0.0.1:43410_solr c:withShardField s:b r:core_node14 x:withShardField_b_replica_n10] o.a.s.s.IndexSchema [withShardField_b_replica_n10] Schema name=cloud-dynamic
[junit4] 2> 455127 INFO (qtp103329444-3434) [n:127.0.0.1:40737_solr c:withShardField s:c r:core_node26 x:withShardField_c_replica_n19] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 455127 INFO (qtp103329444-3434) [n:127.0.0.1:40737_solr c:withShardField s:c r:core_node26 x:withShardField_c_replica_n19] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_c_replica_n19' using configuration from collection withShardField, trusted=true
[junit4] 2> 455127 INFO (qtp103329444-3434) [n:127.0.0.1:40737_solr c:withShardField s:c r:core_node26 x:withShardField_c_replica_n19] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.core.withShardField.c.replica_n19' (registry 'solr.core.withShardField.c.replica_n19') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 455139 INFO (qtp446064310-3258) [n:127.0.0.1:33904_solr c:withShardField s:b r:core_node18 x:withShardField_b_replica_n12] o.a.s.s.IndexSchema [withShardField_b_replica_n12] Schema name=cloud-dynamic
[junit4] 2> 455103 INFO (qtp103329444-3284) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node40 x:withShardField_d_replica_n38] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 455152 INFO (qtp103329444-3284) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node40 x:withShardField_d_replica_n38] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_d_replica_n38' using configuration from collection withShardField, trusted=true
[junit4] 2> 455152 INFO (qtp103329444-3284) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node40 x:withShardField_d_replica_n38] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.core.withShardField.d.replica_n38' (registry 'solr.core.withShardField.d.replica_n38') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 455137 INFO (qtp446064310-3256) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node25 x:withShardField_c_replica_n17] o.a.s.s.IndexSchema [withShardField_c_replica_n17] Schema name=cloud-dynamic
[junit4] 2> 455123 INFO (qtp446064310-3259) [n:127.0.0.1:33904_solr c:withShardField s:a r:core_node6 x:withShardField_a_replica_n4] o.a.s.s.IndexSchema [withShardField_a_replica_n4] Schema name=cloud-dynamic
[junit4] 2> 455332 INFO (qtp1590695156-3293) [n:127.0.0.1:43410_solr c:withShardField s:c r:core_node28 x:withShardField_c_replica_n24] o.a.s.s.IndexSchema [withShardField_c_replica_n24] Schema name=cloud-dynamic
[junit4] 2> 455335 INFO (qtp103329444-3284) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node40 x:withShardField_d_replica_n38] o.a.s.c.SolrCore [[withShardField_d_replica_n38] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/withShardField_d_replica_n38], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/./withShardField_d_replica_n38/data/]
[junit4] 2> 455337 INFO (qtp103329444-3434) [n:127.0.0.1:40737_solr c:withShardField s:c r:core_node26 x:withShardField_c_replica_n19] o.a.s.c.SolrCore [[withShardField_c_replica_n19] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/withShardField_c_replica_n19], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/./withShardField_c_replica_n19/data/]
[junit4] 2> 455389 INFO (qtp103329444-3280) [n:127.0.0.1:40737_solr c:withShardField s:b r:core_node20 x:withShardField_b_replica_n13] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 455389 INFO (qtp103329444-3280) [n:127.0.0.1:40737_solr c:withShardField s:b r:core_node20 x:withShardField_b_replica_n13] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_b_replica_n13' using configuration from collection withShardField, trusted=true
[junit4] 2> 455390 INFO (qtp103329444-3280) [n:127.0.0.1:40737_solr c:withShardField s:b r:core_node20 x:withShardField_b_replica_n13] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.core.withShardField.b.replica_n13' (registry 'solr.core.withShardField.b.replica_n13') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 455390 INFO (qtp103329444-3280) [n:127.0.0.1:40737_solr c:withShardField s:b r:core_node20 x:withShardField_b_replica_n13] o.a.s.c.SolrCore [[withShardField_b_replica_n13] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/withShardField_b_replica_n13], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/./withShardField_b_replica_n13/data/]
[junit4] 2> 455402 INFO (qtp446064310-3262) [n:127.0.0.1:33904_solr c:withShardField s:d r:core_node39 x:withShardField_d_replica_n34] o.a.s.s.IndexSchema [withShardField_d_replica_n34] Schema name=cloud-dynamic
[junit4] 2> 455539 INFO (qtp999718583-3428) [n:127.0.0.1:40679_solr c:withShardField s:d r:core_node36 x:withShardField_d_replica_n32] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 455539 INFO (qtp999718583-3428) [n:127.0.0.1:40679_solr c:withShardField s:d r:core_node36 x:withShardField_d_replica_n32] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_d_replica_n32' using configuration from collection withShardField, trusted=true
[junit4] 2> 455540 INFO (qtp999718583-3428) [n:127.0.0.1:40679_solr c:withShardField s:d r:core_node36 x:withShardField_d_replica_n32] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.core.withShardField.d.replica_n32' (registry 'solr.core.withShardField.d.replica_n32') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 455540 INFO (qtp999718583-3428) [n:127.0.0.1:40679_solr c:withShardField s:d r:core_node36 x:withShardField_d_replica_n32] o.a.s.c.SolrCore [[withShardField_d_replica_n32] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/withShardField_d_replica_n32], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/./withShardField_d_replica_n32/data/]
[junit4] 2> 455596 INFO (qtp1590695156-3276) [n:127.0.0.1:43410_solr c:withShardField s:b r:core_node23 x:withShardField_b_replica_n16] o.a.s.s.IndexSchema [withShardField_b_replica_n16] Schema name=cloud-dynamic
[junit4] 2> 455600 INFO (qtp446064310-3259) [n:127.0.0.1:33904_solr c:withShardField s:a r:core_node6 x:withShardField_a_replica_n4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 455600 INFO (qtp446064310-3259) [n:127.0.0.1:33904_solr c:withShardField s:a r:core_node6 x:withShardField_a_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_a_replica_n4' using configuration from collection withShardField, trusted=true
[junit4] 2> 455601 INFO (qtp446064310-3259) [n:127.0.0.1:33904_solr c:withShardField s:a r:core_node6 x:withShardField_a_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33904.solr.core.withShardField.a.replica_n4' (registry 'solr.core.withShardField.a.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 455601 INFO (qtp446064310-3259) [n:127.0.0.1:33904_solr c:withShardField s:a r:core_node6 x:withShardField_a_replica_n4] o.a.s.c.SolrCore [[withShardField_a_replica_n4] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node4/withShardField_a_replica_n4], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node4/./withShardField_a_replica_n4/data/]
[junit4] 2> 455859 INFO (qtp999718583-3273) [n:127.0.0.1:40679_solr c:withShardField s:c r:core_node27 x:withShardField_c_replica_n21] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 455860 INFO (qtp999718583-3273) [n:127.0.0.1:40679_solr c:withShardField s:c r:core_node27 x:withShardField_c_replica_n21] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_c_replica_n21' using configuration from collection withShardField, trusted=true
[junit4] 2> 455860 INFO (qtp999718583-3273) [n:127.0.0.1:40679_solr c:withShardField s:c r:core_node27 x:withShardField_c_replica_n21] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.core.withShardField.c.replica_n21' (registry 'solr.core.withShardField.c.replica_n21') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 455860 INFO (qtp999718583-3273) [n:127.0.0.1:40679_solr c:withShardField s:c r:core_node27 x:withShardField_c_replica_n21] o.a.s.c.SolrCore [[withShardField_c_replica_n21] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/withShardField_c_replica_n21], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/./withShardField_c_replica_n21/data/]
[junit4] 2> 456186 INFO (qtp1590695156-3294) [n:127.0.0.1:43410_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 456186 INFO (qtp1590695156-3294) [n:127.0.0.1:43410_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_a_replica_n2' using configuration from collection withShardField, trusted=true
[junit4] 2> 456186 INFO (qtp446064310-3256) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node25 x:withShardField_c_replica_n17] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 456186 INFO (qtp446064310-3256) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node25 x:withShardField_c_replica_n17] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_c_replica_n17' using configuration from collection withShardField, trusted=true
[junit4] 2> 456186 INFO (qtp1590695156-3294) [n:127.0.0.1:43410_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43410.solr.core.withShardField.a.replica_n2' (registry 'solr.core.withShardField.a.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 456187 INFO (qtp446064310-3256) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node25 x:withShardField_c_replica_n17] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33904.solr.core.withShardField.c.replica_n17' (registry 'solr.core.withShardField.c.replica_n17') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 456187 INFO (qtp1590695156-3294) [n:127.0.0.1:43410_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.c.SolrCore [[withShardField_a_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node2/withShardField_a_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node2/./withShardField_a_replica_n2/data/]
[junit4] 2> 456187 INFO (qtp446064310-3256) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node25 x:withShardField_c_replica_n17] o.a.s.c.SolrCore [[withShardField_c_replica_n17] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node4/withShardField_c_replica_n17], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node4/./withShardField_c_replica_n17/data/]
[junit4] 2> 456203 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node3 x:withShardField_a_replica_n1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 456203 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node3 x:withShardField_a_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_a_replica_n1' using configuration from collection withShardField, trusted=true
[junit4] 2> 456203 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node3 x:withShardField_a_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40679.solr.core.withShardField.a.replica_n1' (registry 'solr.core.withShardField.a.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 456203 INFO (qtp999718583-3266) [n:127.0.0.1:40679_solr c:withShardField s:a r:core_node3 x:withShardField_a_replica_n1] o.a.s.c.SolrCore [[withShardField_a_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/withShardField_a_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node3/./withShardField_a_replica_n1/data/]
[junit4] 2> 456205 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 456205 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 456207 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 456207 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 456208 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.s.SolrIndexSearcher Opening [Searcher@7bc3aad1[withShardField_d_replica_n30] main]
[junit4] 2> 456210 INFO (qtp103329444-3281) [n:127.0.0.1:40737_solr c:withShardField s:a r:core_node9 x:withShardField_a_replica_n7] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 456210 INFO (qtp103329444-3281) [n:127.0.0.1:40737_solr c:withShardField s:a r:core_node9 x:withShardField_a_replica_n7] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_a_replica_n7' using configuration from collection withShardField, trusted=true
[junit4] 2> 456211 INFO (qtp103329444-3281) [n:127.0.0.1:40737_solr c:withShardField s:a r:core_node9 x:withShardField_a_replica_n7] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40737.solr.core.withShardField.a.replica_n7' (registry 'solr.core.withShardField.a.replica_n7') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 456211 INFO (qtp103329444-3281) [n:127.0.0.1:40737_solr c:withShardField s:a r:core_node9 x:withShardField_a_replica_n7] o.a.s.c.SolrCore [[withShardField_a_replica_n7] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/withShardField_a_replica_n7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node1/./withShardField_a_replica_n7/data/]
[junit4] 2> 456223 INFO (qtp1590695156-3293) [n:127.0.0.1:43410_solr c:withShardField s:c r:core_node28 x:withShardField_c_replica_n24] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 456223 INFO (qtp1590695156-3293) [n:127.0.0.1:43410_solr c:withShardField s:c r:core_node28 x:withShardField_c_replica_n24] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_c_replica_n24' using configuration from collection withShardField, trusted=true
[junit4] 2> 456223 INFO (qtp1590695156-3293) [n:127.0.0.1:43410_solr c:withShardField s:c r:core_node28 x:withShardField_c_replica_n24] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43410.solr.core.withShardField.c.replica_n24' (registry 'solr.core.withShardField.c.replica_n24') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 456224 INFO (qtp1590695156-3293) [n:127.0.0.1:43410_solr c:withShardField s:c r:core_node28 x:withShardField_c_replica_n24] o.a.s.c.SolrCore [[withShardField_c_replica_n24] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node2/withShardField_c_replica_n24], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node2/./withShardField_c_replica_n24/data/]
[junit4] 2> 456229 INFO (qtp446064310-3255) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node31 x:withShardField_c_replica_n29] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 456229 INFO (qtp446064310-3255) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node31 x:withShardField_c_replica_n29] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_c_replica_n29' using configuration from collection withShardField, trusted=true
[junit4] 2> 456229 INFO (qtp446064310-3255) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node31 x:withShardField_c_replica_n29] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33904.solr.core.withShardField.c.replica_n29' (registry 'solr.core.withShardField.c.replica_n29') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 456242 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 456242 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 456243 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 456243 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1611109890730754048
[junit4] 2> 456275 INFO (searcherExecutor-1642-thread-1-processing-n:127.0.0.1:40737_solr x:withShardField_d_replica_n30 c:withShardField s:d r:core_node35) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.c.SolrCore [withShardField_d_replica_n30] Registered new searcher Searcher@7bc3aad1[withShardField_d_replica_n30] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 456300 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.c.ZkShardTerms Successful update of terms at /collections/withShardField/terms/d to Terms{values={core_node35=0}, version=0}
[junit4] 2> 456332 INFO (qtp446064310-3255) [n:127.0.0.1:33904_solr c:withShardField s:c r:core_node31 x:withShardField_c_replica_n29] o.a.s.c.SolrCore [[withShardField_c_replica_n29] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node4/withShardField_c_replica_n29], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CustomCollectionTest_97126369971BF459-001/tempDir-001/node4/./withShardField_c_replica_n29/data/]
[junit4] 2> 456337 INFO (qtp103329444-3275) [n:127.0.0.1:40737_solr c:withShardField s:d r:core_node35 x:withShardField_d_replica_n30] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard d: total=5 found=1 timeoutin=9979ms
[junit4] 2> 456340 INFO (qtp446064310-3262) [n:127.0.0.1:33904_solr c:withShardField s:d r:core_node39 x:withShardField_d_replica_n34] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 456340 INFO (qtp446064310-3262) [n:127.0.0.1:33904_solr c:withShardField s:d r:core_node39 x:withShardField_d_replica_n34] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_d_replica_n34' using configuration from collection withShardField, trusted=true
[junit4] 2> 456341 INFO (qtp446064310-3262) [n:127.0.0.1:33904_solr c:withShardField s:d r:core_node39 x:withShardField_d_replica_n34] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33904.solr.core.withShardField.d.replica_n34' (registry 'solr.core.withShardField.d.replica_n34') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@48c6a4ac
[junit4] 2> 456341 INFO (qtp446064310-3262) [n:127.0.0.1:33904_solr c:withShardField s:d r:core_node39 x:withShardField_d_replica_n34] o.a.s.c.SolrCore [[withShardField_d_replica_n34] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections
[...truncated too long message...]
rty 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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1183043549
[copy] Copying 239 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1183043549
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1183043549
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 28ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 209 minutes 11 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
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:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
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:1819)
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:2920)
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:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
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:1819)
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)
[JENKINS] Lucene-Solr-Tests-7.x - Build # 867 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/867/
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest.testBelowSearchRate
Error Message:
[Op{action=DELETEREPLICA, hints={COLL_SHARD=[{ "first":"belowRate_collection", "second":"shard1"}], REPLICA=[core_node3]}}, Op{action=DELETEREPLICA, hints={COLL_SHARD=[{ "first":"belowRate_collection", "second":"shard1"}], REPLICA=[core_node6]}}, Op{action=DELETEREPLICA, hints={COLL_SHARD=[{ "first":"belowRate_collection", "second":"shard1"}], REPLICA=[core_node8]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:46752_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:41730_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:45919_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:42588_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:33423_solr]}}] expected:<7> but was:<8>
Stack Trace:
java.lang.AssertionError: [Op{action=DELETEREPLICA, hints={COLL_SHARD=[{
"first":"belowRate_collection",
"second":"shard1"}], REPLICA=[core_node3]}}, Op{action=DELETEREPLICA, hints={COLL_SHARD=[{
"first":"belowRate_collection",
"second":"shard1"}], REPLICA=[core_node6]}}, Op{action=DELETEREPLICA, hints={COLL_SHARD=[{
"first":"belowRate_collection",
"second":"shard1"}], REPLICA=[core_node8]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:46752_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:41730_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:45919_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:42588_solr]}}, Op{action=NONE, hints={SRC_NODE=[127.0.0.1:33423_solr]}}] expected:<7> but was:<8>
at __randomizedtesting.SeedInfo.seed([3AEBEB3585581185:50464D55DDB09496]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest.testBelowSearchRate(SearchRateTriggerIntegrationTest.java:398)
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:1742)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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 12509 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/init-core-data-001
[junit4] 2> 176970 WARN (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 176970 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 177025 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 177025 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 177026 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001
[junit4] 2> 177026 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 177040 INFO (Thread-160) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 177040 INFO (Thread-160) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 177166 ERROR (Thread-160) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 177215 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44255
[junit4] 2> 177288 INFO (zkConnectionManagerCallback-133-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 177387 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 177389 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 177423 INFO (jetty-launcher-130-thread-4) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 177443 INFO (jetty-launcher-130-thread-3) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 177472 INFO (jetty-launcher-130-thread-5) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 177508 INFO (jetty-launcher-130-thread-5) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 177508 INFO (jetty-launcher-130-thread-5) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 177508 INFO (jetty-launcher-130-thread-5) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 177508 INFO (jetty-launcher-130-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@477e63f{/solr,null,AVAILABLE}
[junit4] 2> 177544 INFO (jetty-launcher-130-thread-5) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3845bc7a{SSL,[ssl, http/1.1]}{127.0.0.1:33423}
[junit4] 2> 177544 INFO (jetty-launcher-130-thread-5) [ ] o.e.j.s.Server Started @177612ms
[junit4] 2> 177544 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33423}
[junit4] 2> 177545 ERROR (jetty-launcher-130-thread-5) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 177545 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 177545 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 177545 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 177545 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 177545 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T10:18:16.426Z
[junit4] 2> 177565 INFO (jetty-launcher-130-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 177565 INFO (jetty-launcher-130-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 177565 INFO (jetty-launcher-130-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 177565 INFO (jetty-launcher-130-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@afb0436{/solr,null,AVAILABLE}
[junit4] 2> 177566 INFO (jetty-launcher-130-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7ff4f018{SSL,[ssl, http/1.1]}{127.0.0.1:41730}
[junit4] 2> 177568 INFO (jetty-launcher-130-thread-3) [ ] o.e.j.s.Server Started @177635ms
[junit4] 2> 177568 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41730}
[junit4] 2> 177568 ERROR (jetty-launcher-130-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 177568 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 177568 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 177568 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 177568 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 177568 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T10:18:16.449Z
[junit4] 2> 177598 INFO (jetty-launcher-130-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 177598 INFO (jetty-launcher-130-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 177598 INFO (jetty-launcher-130-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 177599 INFO (jetty-launcher-130-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@caacb3a{/solr,null,AVAILABLE}
[junit4] 2> 177790 INFO (jetty-launcher-130-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@52bf4aa3{SSL,[ssl, http/1.1]}{127.0.0.1:42588}
[junit4] 2> 177790 INFO (jetty-launcher-130-thread-4) [ ] o.e.j.s.Server Started @177857ms
[junit4] 2> 177790 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42588}
[junit4] 2> 177791 ERROR (jetty-launcher-130-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 177791 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 177791 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 177791 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 177791 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 177791 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T10:18:16.672Z
[junit4] 2> 177899 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 177899 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 177900 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 178008 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 178008 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 178008 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 178009 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1890bdea{/solr,null,AVAILABLE}
[junit4] 2> 178010 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4b12a2eb{SSL,[ssl, http/1.1]}{127.0.0.1:46752}
[junit4] 2> 178010 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.Server Started @178077ms
[junit4] 2> 178010 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46752}
[junit4] 2> 178010 ERROR (jetty-launcher-130-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 178010 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 178010 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 178010 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 178010 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 178011 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T10:18:16.891Z
[junit4] 2> 178081 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@448fb942{/solr,null,AVAILABLE}
[junit4] 2> 178118 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178139 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@44853005{SSL,[ssl, http/1.1]}{127.0.0.1:45919}
[junit4] 2> 178139 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.Server Started @178207ms
[junit4] 2> 178139 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45919}
[junit4] 2> 178140 ERROR (jetty-launcher-130-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 178140 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 178140 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 178140 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 178140 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 178140 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-09T10:18:17.021Z
[junit4] 2> 178215 INFO (zkConnectionManagerCallback-141-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178253 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 178265 INFO (zkConnectionManagerCallback-135-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178390 INFO (zkConnectionManagerCallback-143-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178411 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 0x1046219567f0001, likely client has closed socket
[junit4] 2> 178413 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 178413 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 178423 INFO (zkConnectionManagerCallback-137-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178683 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 178683 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 179359 INFO (jetty-launcher-130-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44255/solr
[junit4] 2> 179589 INFO (zkConnectionManagerCallback-147-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 180231 INFO (zkConnectionManagerCallback-149-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 180478 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44255/solr
[junit4] 2> 180741 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44255/solr
[junit4] 2> 180918 INFO (zkConnectionManagerCallback-153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181097 INFO (zkConnectionManagerCallback-157-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181416 INFO (zkConnectionManagerCallback-163-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181435 INFO (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 182902 INFO (jetty-launcher-130-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44255/solr
[junit4] 2> 183130 INFO (zkConnectionManagerCallback-171-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 183173 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 0x1046219567f000c, likely client has closed socket
[junit4] 2> 183354 INFO (zkConnectionManagerCallback-173-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 183577 INFO (jetty-launcher-130-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44255/solr
[junit4] 2> 183614 INFO (zkConnectionManagerCallback-177-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 183745 INFO (zkConnectionManagerCallback-179-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 183885 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41730_solr
[junit4] 2> 183905 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.c.Overseer Overseer (id=73291354909638671-127.0.0.1:41730_solr-n_0000000000) starting
[junit4] 2> 183926 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42588_solr
[junit4] 2> 184014 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 184015 INFO (zkCallback-178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 184233 INFO (zkConnectionManagerCallback-188-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 184327 INFO (zkConnectionManagerCallback-193-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 184327 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 184363 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 184368 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44255/solr ready
[junit4] 2> 184368 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 184436 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44255/solr ready
[junit4] 2> 184439 INFO (OverseerStateUpdate-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [n:127.0.0.1:41730_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41730_solr
[junit4] 2> 184510 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 184566 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 184566 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41730_solr
[junit4] 2> 184583 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 184583 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 184583 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 184627 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42588.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 184636 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 184656 INFO (zkCallback-178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 184855 INFO (zkCallback-187-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 184855 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 184880 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42588.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 185035 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42588.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 185036 INFO (jetty-launcher-130-thread-4) [n:127.0.0.1:42588_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node4/.
[junit4] 2> 184966 INFO (zkCallback-192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 185075 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 185109 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 185146 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 185146 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46752_solr
[junit4] 2> 185238 INFO (zkCallback-148-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 185239 INFO (zkCallback-178-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 185258 INFO (zkCallback-187-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 185258 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 185275 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 185401 INFO (zkCallback-162-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 185494 INFO (zkCallback-160-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 185681 INFO (zkCallback-192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 185814 INFO (zkConnectionManagerCallback-200-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 185820 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:46752_solr, 127.0.0.1:41730_solr, 127.0.0.1:42588_solr]
[junit4] 2> 185820 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 185820 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33423_solr
[junit4] 2> 185822 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41730.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 185917 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 185922 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 185922 INFO (zkCallback-178-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 185938 INFO (zkCallback-187-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 185938 INFO (zkCallback-148-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 185938 INFO (zkCallback-162-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 185938 INFO (zkCallback-160-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 185938 INFO (zkCallback-192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 185939 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 185939 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 185958 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41730.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 185976 DEBUG (ScheduledTrigger-749-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4
[junit4] 2> 185976 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41730.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 185977 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44255/solr ready
[junit4] 2> 185992 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 186003 INFO (jetty-launcher-130-thread-3) [n:127.0.0.1:41730_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node3/.
[junit4] 2> 186007 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 186007 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45919_solr
[junit4] 2> 186084 INFO (zkCallback-178-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186084 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186128 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46752.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186142 INFO (zkCallback-162-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186142 INFO (zkCallback-187-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186142 INFO (zkCallback-148-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186180 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46752.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186180 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46752.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186181 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:46752_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node1/.
[junit4] 2> 186235 INFO (zkCallback-199-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186370 INFO (zkCallback-192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186381 INFO (zkCallback-160-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 186400 INFO (zkConnectionManagerCallback-206-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 186467 INFO (zkConnectionManagerCallback-211-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 186532 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 186532 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 186533 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44255/solr ready
[junit4] 2> 186544 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 186601 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33423.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186626 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44255/solr ready
[junit4] 2> 186634 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 186683 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33423.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186684 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33423.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186685 INFO (jetty-launcher-130-thread-5) [n:127.0.0.1:33423_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node5/.
[junit4] 2> 186710 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45919.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186745 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45919.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186745 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45919.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 186746 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:45919_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node2/.
[junit4] 2> 187037 DEBUG (ScheduledTrigger-749-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 187153 INFO (zkConnectionManagerCallback-215-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187191 INFO (zkConnectionManagerCallback-220-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187212 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 187214 INFO (SUITE-SearchRateTriggerIntegrationTest-seed#[3AEBEB3585581185]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44255/solr ready
[junit4] 2> 187938 INFO (qtp435058598-905) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=118
[junit4] 2> 188082 DEBUG (ScheduledTrigger-749-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 188271 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=21
[junit4] 2> 188517 INFO (qtp533651731-884) [n:127.0.0.1:41730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 188615 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 188897 INFO (qtp2087916157-875) [n:127.0.0.1:33423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 188899 DEBUG (qtp435058598-900) [n:127.0.0.1:45919_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 188914 INFO (qtp435058598-900) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=1345
[junit4] 2> 188920 DEBUG (zkCallback-192-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] IGNOR/A 0.02s J2 | SearchRateTriggerIntegrationTest.testDeleteNode
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] 2> 189083 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 189140 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 189140 DEBUG (ScheduledTrigger-749-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 189141 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 189452 INFO (TEST-SearchRateTriggerIntegrationTest.testAboveSearchRate-seed#[3AEBEB3585581185]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAboveSearchRate
[junit4] 2> 189508 INFO (TEST-SearchRateTriggerIntegrationTest.testAboveSearchRate-seed#[3AEBEB3585581185]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 189619 INFO (TEST-SearchRateTriggerIntegrationTest.testAboveSearchRate-seed#[3AEBEB3585581185]) [ ] o.a.s.c.a.SearchRateTriggerIntegrationTest /autoscaling.json reset, new znode version 3
[junit4] 2> 189632 DEBUG (zkCallback-192-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 189632 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 189633 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 189634 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 189634 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 194717 INFO (qtp435058598-903) [n:127.0.0.1:45919_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=aboveRate_collection&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 194755 INFO (OverseerThreadFactory-751-thread-1-processing-n:127.0.0.1:41730_solr) [n:127.0.0.1:41730_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection aboveRate_collection
[junit4] 2> 194988 INFO (OverseerStateUpdate-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [n:127.0.0.1:41730_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"aboveRate_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"aboveRate_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46752/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 195044 INFO (OverseerStateUpdate-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [n:127.0.0.1:41730_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"aboveRate_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"aboveRate_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42588/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 195468 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr x:aboveRate_collection_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=aboveRate_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=aboveRate_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 195469 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr x:aboveRate_collection_shard1_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 195477 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr x:aboveRate_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=aboveRate_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=aboveRate_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 196710 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 196835 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 196946 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.s.IndexSchema [aboveRate_collection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 197331 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.s.IndexSchema [aboveRate_collection_shard1_replica_n2] Schema name=minimal
[junit4] 2> 197334 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 197334 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'aboveRate_collection_shard1_replica_n1' using configuration from collection aboveRate_collection, trusted=true
[junit4] 2> 197379 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 197379 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'aboveRate_collection_shard1_replica_n2' using configuration from collection aboveRate_collection, trusted=true
[junit4] 2> 197380 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42588.solr.core.aboveRate_collection.shard1.replica_n2' (registry 'solr.core.aboveRate_collection.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 197380 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.SolrCore [[aboveRate_collection_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node4/aboveRate_collection_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node4/./aboveRate_collection_shard1_replica_n2/data/]
[junit4] 2> 197432 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46752.solr.core.aboveRate_collection.shard1.replica_n1' (registry 'solr.core.aboveRate_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@651bd0b3
[junit4] 2> 197432 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.SolrCore [[aboveRate_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node1/aboveRate_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_3AEBEB3585581185-001/tempDir-001/node1/./aboveRate_collection_shard1_replica_n1/data/]
[junit4] 2> 198237 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 198238 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 198239 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 198239 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 198241 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4dc3387b[aboveRate_collection_shard1_replica_n1] main]
[junit4] 2> 198241 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 198330 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 198348 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 198348 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 198349 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 198349 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1611124773727764480
[junit4] 2> 198350 INFO (searcherExecutor-769-thread-1-processing-n:127.0.0.1:46752_solr x:aboveRate_collection_shard1_replica_n1 c:aboveRate_collection s:shard1 r:core_node3) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.SolrCore [aboveRate_collection_shard1_replica_n1] Registered new searcher Searcher@4dc3387b[aboveRate_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 198384 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 198384 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 198386 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@70326ccf[aboveRate_collection_shard1_replica_n2] main]
[junit4] 2> 198405 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 198405 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 198406 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 198407 INFO (searcherExecutor-770-thread-1-processing-n:127.0.0.1:42588_solr x:aboveRate_collection_shard1_replica_n2 c:aboveRate_collection s:shard1 r:core_node4) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.SolrCore [aboveRate_collection_shard1_replica_n2] Registered new searcher Searcher@70326ccf[aboveRate_collection_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 198407 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1611124773788581888
[junit4] 2> 198439 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/aboveRate_collection/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 198492 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/aboveRate_collection/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 198604 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 198604 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 198604 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42588/solr/aboveRate_collection_shard1_replica_n2/
[junit4] 2> 198606 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=aboveRate_collection_shard1_replica_n2 url=https://127.0.0.1:42588/solr START replicas=[https://127.0.0.1:46752/solr/aboveRate_collection_shard1_replica_n1/] nUpdates=100
[junit4] 2> 198624 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=aboveRate_collection_shard1_replica_n2 url=https://127.0.0.1:42588/solr DONE. We have no versions. sync failed.
[junit4] 2> 198772 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=22
[junit4] 2> 198782 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 198783 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 198783 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 198804 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42588/solr/aboveRate_collection_shard1_replica_n2/ shard1
[junit4] 2> 198933 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 198972 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=aboveRate_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=aboveRate_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3504
[junit4] 2> 199052 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/aboveRate_collection/state.json] for collection [aboveRate_collection] has occurred - updating... (live nodes size: [5])
[junit4] 2> 199592 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=aboveRate_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=aboveRate_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4158
[junit4] 2> 199728 INFO (qtp435058598-903) [n:127.0.0.1:45919_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 199783 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/aboveRate_collection/state.json] for collection [aboveRate_collection] has occurred - updating... (live nodes size: [5])
[junit4] 2> 199786 INFO (zkCallback-162-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/aboveRate_collection/state.json] for collection [aboveRate_collection] has occurred - updating... (live nodes size: [5])
[junit4] 2> 200773 INFO (OverseerCollectionConfigSetProcessor-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [n:127.0.0.1:41730_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 200803 INFO (qtp435058598-903) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=aboveRate_collection&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=6086
[junit4] 2> 201063 INFO (qtp435058598-903) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 201209 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=2
[junit4] 2> 201245 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=2
[junit4] 2> 201352 INFO (qtp533651731-887) [n:127.0.0.1:41730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 201389 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n2:INDEX.sizeInBytes} status=0 QTime=18
[junit4] 2> 201467 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 201597 INFO (qtp2087916157-875) [n:127.0.0.1:33423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 201608 DEBUG (qtp533651731-880) [n:127.0.0.1:41730_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 201608 INFO (qtp533651731-880) [n:127.0.0.1:41730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=713
[junit4] 2> 201610 DEBUG (zkCallback-192-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 201610 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 201610 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 201611 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 201611 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 201744 INFO (qtp435058598-903) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 201833 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=1
[junit4] 2> 201845 INFO (qtp917106176-916) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=10
[junit4] 2> 201906 INFO (qtp533651731-887) [n:127.0.0.1:41730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202071 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n2:INDEX.sizeInBytes} status=0 QTime=16
[junit4] 2> 202074 INFO (qtp2129553323-896) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202143 INFO (qtp2087916157-878) [n:127.0.0.1:33423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202153 DEBUG (qtp917106176-914) [n:127.0.0.1:46752_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 202154 INFO (qtp917106176-914) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=502
[junit4] 2> 202189 DEBUG (zkCallback-192-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 202190 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 202190 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 202190 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 202191 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 202207 INFO (qtp435058598-905) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202212 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 202248 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=18
[junit4] 2> 202265 INFO (qtp533651731-884) [n:127.0.0.1:41730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202300 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 202303 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202411 INFO (qtp2087916157-875) [n:127.0.0.1:33423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202430 DEBUG (qtp533651731-886) [n:127.0.0.1:41730_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 202431 DEBUG (zkCallback-192-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 202431 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 202431 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 202432 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 202432 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 202432 INFO (qtp533651731-886) [n:127.0.0.1:41730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=276
[junit4] 2> 202496 INFO (qtp435058598-902) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202532 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 202625 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=57
[junit4] 2> 202644 INFO (qtp533651731-884) [n:127.0.0.1:41730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202679 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.aboveRate_collection.shard1.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 202700 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202717 INFO (qtp2087916157-877) [n:127.0.0.1:33423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 202731 DEBUG (qtp435058598-905) [n:127.0.0.1:45919_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 202735 DEBUG (zkCallback-192-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 202735 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 202736 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 202736 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 202736 DEBUG (OverseerAutoScalingTriggerThread-73291354909638671-127.0.0.1:41730_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 202750 INFO (qtp435058598-905) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=284
[junit4] 2> 202809 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=50
[junit4] 2> 203078 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203092 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=12
[junit4] 2> 203101 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203114 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203115 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203131 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 203138 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203150 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203153 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203188 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203224 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203226 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203299 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203334 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203350 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203378 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203386 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203459 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203461 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203564 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=33
[junit4] 2> 203567 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203603 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203624 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203639 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203640 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203710 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=33
[junit4] 2> 203712 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203771 INFO (qtp2129553323-895) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=40
[junit4] 2> 203800 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203820 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203823 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203858 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203859 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203893 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203941 INFO (qtp917106176-911) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=16
[junit4] 2> 203945 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203948 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203971 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203973 INFO (qtp917106176-915) [n:127.0.0.1:46752_solr c:aboveRate_collection s:shard1 r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203991 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 203992 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr c:aboveRate_collection s:shard1 r:core_node4 x:aboveRate_collection_shard1_replica_n2] o.a.s.c.S.Request [aboveRate_collection_shard1_replica_n2] webapp=/solr path=/select params={q=*:*&_stateVer_=aboveRate_collection:6&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 204006 INFO (qtp2129553323-893) [n:127.0.0.1:42588_solr
[...truncated too long message...]
rty 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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1139025092
[copy] Copying 239 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1139025092
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1139025092
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 28ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 205 minutes 25 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
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:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
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:1819)
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:2920)
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:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
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:1819)
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)