Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1557/
2 tests failed. FAILED: org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionReload Error Message: Error from server at http://127.0.0.1:47324/solr: create the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47324/solr: create the collection time out:180s at __randomizedtesting.SeedInfo.seed([846794C8C1186B6A:85DEB8F61497C4F5]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionReload(CollectionsAPIDistributedZkTest.java:489) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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.api.collections.CollectionsAPIDistributedZkTest.testMissingRequiredParameters Error Message: Error from server at http://127.0.0.1:47324/solr: Could not find collection : reloaded_collection Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47324/solr: Could not find collection : reloaded_collection at __randomizedtesting.SeedInfo.seed([846794C8C1186B6A:CBA165AE5C915CCB]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:454) at org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.clearCluster(CollectionsAPIDistributedZkTest.java:115) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:968) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 13589 lines...] [junit4] Suite: org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/init-core-data-001 [junit4] 2> 902834 WARN (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=44 numCloses=44 [junit4] 2> 902839 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 902841 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-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> 902841 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 902842 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001 [junit4] 2> 902842 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 902860 INFO (Thread-4261) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 902860 INFO (Thread-4261) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 903606 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:55216 [junit4] 2> 903607 ERROR (Thread-4261) [ ] 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> 903764 INFO (zkConnectionManagerCallback-851-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 903797 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 903815 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 903828 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 903842 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 904073 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 904073 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 904073 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 904084 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@447fe5ac{/solr,null,AVAILABLE} [junit4] 2> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2f5d9d0e{HTTP/1.1,[http/1.1]}{127.0.0.1:37200} [junit4] 2> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.e.j.s.Server Started @904162ms [junit4] 2> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37200} [junit4] 2> 904087 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> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 904087 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-03T01:48:05.166Z [junit4] 2> 904120 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 904120 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 904120 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 904121 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 904121 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 904121 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 904121 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3b234e3f{/solr,null,AVAILABLE} [junit4] 2> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b627876{HTTP/1.1,[http/1.1]}{127.0.0.1:44803} [junit4] 2> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.e.j.s.Server Started @904197ms [junit4] 2> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44803} [junit4] 2> 904122 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> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 904122 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-03T01:48:05.201Z [junit4] 2> 904248 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 904248 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 904248 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 904252 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@bb6ca78{/solr,null,AVAILABLE} [junit4] 2> 904252 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3f8229be{HTTP/1.1,[http/1.1]}{127.0.0.1:47324} [junit4] 2> 904252 INFO (jetty-launcher-848-thread-4) [ ] o.e.j.s.Server Started @904328ms [junit4] 2> 904253 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=47324} [junit4] 2> 904253 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> 904253 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 904253 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 904253 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 904253 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 904253 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-03T01:48:05.332Z [junit4] 2> 904300 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4b43041a{/solr,null,AVAILABLE} [junit4] 2> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2d205d49{HTTP/1.1,[http/1.1]}{127.0.0.1:44976} [junit4] 2> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.e.j.s.Server Started @904376ms [junit4] 2> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44976} [junit4] 2> 904301 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> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 904301 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-06-03T01:48:05.380Z [junit4] 2> 904312 INFO (zkConnectionManagerCallback-857-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904313 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 904317 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 904317 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 904324 INFO (zkConnectionManagerCallback-853-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904324 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 904328 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 904328 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 904336 INFO (zkConnectionManagerCallback-855-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904337 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 904340 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 904340 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 904368 INFO (zkConnectionManagerCallback-859-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904368 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 904372 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 904372 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 904394 INFO (jetty-launcher-848-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 904495 INFO (jetty-launcher-848-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 904497 INFO (zkConnectionManagerCallback-863-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904520 INFO (jetty-launcher-848-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 904558 INFO (jetty-launcher-848-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 904571 INFO (zkConnectionManagerCallback-869-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904622 INFO (zkConnectionManagerCallback-874-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904635 INFO (zkConnectionManagerCallback-871-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904678 INFO (zkConnectionManagerCallback-877-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904679 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 0x106936088c30008, likely client has closed socket [junit4] 2> 904699 INFO (zkConnectionManagerCallback-879-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904715 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 904716 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37200_solr [junit4] 2> 904718 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.Overseer Overseer (id=73908486718816266-127.0.0.1:37200_solr-n_0000000000) starting [junit4] 2> 904747 INFO (zkConnectionManagerCallback-883-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904772 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 904775 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44976_solr [junit4] 2> 904829 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 904832 INFO (zkConnectionManagerCallback-885-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 904841 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 904843 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 904846 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 904846 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44803_solr [junit4] 2> 904907 INFO (zkCallback-882-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 904907 INFO (zkCallback-878-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 904907 INFO (zkCallback-884-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 905031 INFO (zkConnectionManagerCallback-894-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 905033 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 905036 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 905036 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47324_solr [junit4] 2> 905045 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 905046 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55216/solr ready [junit4] 2> 905066 INFO (OverseerStateUpdate-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 905250 INFO (zkCallback-884-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 905250 INFO (zkCallback-882-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 905252 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 905253 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37200_solr [junit4] 2> 905256 INFO (zkCallback-882-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 905256 INFO (zkCallback-884-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 905269 INFO (zkCallback-870-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 905297 INFO (zkCallback-878-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 905312 INFO (zkCallback-870-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 905351 INFO (zkCallback-893-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 905980 INFO (zkConnectionManagerCallback-902-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 905981 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 905982 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55216/solr ready [junit4] 2> 905988 WARN (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.h.a.MetricsHistoryHandler Missing .system, keeping metrics history in memory [junit4] 2> 906099 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906164 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906164 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906168 INFO (jetty-launcher-848-thread-4) [n:127.0.0.1:47324_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node4 [junit4] 2> 906316 WARN (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.h.a.MetricsHistoryHandler Missing .system, keeping metrics history in memory [junit4] 2> 906458 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906482 INFO (zkConnectionManagerCallback-915-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 906484 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 906485 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55216/solr ready [junit4] 2> 906491 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906491 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906498 INFO (zkConnectionManagerCallback-911-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 906501 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 906504 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55216/solr ready [junit4] 2> 906507 WARN (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.h.a.MetricsHistoryHandler Missing .system, keeping metrics history in memory [junit4] 2> 906516 INFO (jetty-launcher-848-thread-3) [n:127.0.0.1:37200_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node3 [junit4] 2> 906540 WARN (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.h.a.MetricsHistoryHandler Missing .system, keeping metrics history in memory [junit4] 2> 906553 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906600 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906616 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906616 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906622 INFO (jetty-launcher-848-thread-1) [n:127.0.0.1:44803_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node1 [junit4] 2> 906638 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906638 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 906647 INFO (jetty-launcher-848-thread-2) [n:127.0.0.1:44976_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node2 [junit4] 2> 907032 INFO (zkConnectionManagerCallback-917-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 907064 INFO (zkConnectionManagerCallback-922-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 907066 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 907067 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[846794C8C1186B6A]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55216/solr ready [junit4] 2> 907277 INFO (TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNoConfigSetExist [junit4] 2> 907279 INFO (TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[846794C8C1186B6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 907297 INFO (qtp716349888-7116) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf123&name=noconfig&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 907303 INFO (OverseerThreadFactory-1451-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection noconfig [junit4] 2> 907304 ERROR (OverseerThreadFactory-1451-thread-1) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: noconfig operation: create failed:org.apache.solr.common.SolrException: Can not find the specified config set: conf123 [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.validateConfigOrThrowSolrException(OverseerCollectionMessageHandler.java:744) [junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:114) [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:257) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 907342 INFO (qtp716349888-7116) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf123&name=noconfig&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=400 QTime=45 [junit4] 2> 907344 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] 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> 908375 INFO (qtp716349888-7113) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 908384 INFO (qtp716349888-7113) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=8 [junit4] 2> 908385 INFO (TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNoConfigSetExist [junit4] 2> 908511 INFO (TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZeroNumShards [junit4] 2> 908513 INFO (TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[846794C8C1186B6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 908522 INFO (qtp716349888-7113) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&numShards=0&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 908550 INFO (OverseerThreadFactory-1451-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection acollection [junit4] 2> 908557 ERROR (OverseerThreadFactory-1451-thread-2) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: acollection operation: create failed:org.apache.solr.common.SolrException: numShards must be > 0 [junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.buildReplicaPositions(CreateCollectionCmd.java:287) [junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:123) [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:257) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 908561 INFO (qtp716349888-7113) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&numShards=0&wt=javabin&version=2} status=400 QTime=38 [junit4] 2> 908562 INFO (TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZeroNumShards [junit4] 2> 908564 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 908630 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas [junit4] 2> 908635 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[846794C8C1186B6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 908637 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 908648 INFO (OverseerThreadFactory-1451-thread-3) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection [junit4] 2> 908649 WARN (OverseerThreadFactory-1451-thread-3) [ ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 10 on collection collection 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> 908650 ERROR (OverseerThreadFactory-1451-thread-3) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: collection operation: create failed:org.apache.solr.common.SolrException: Cannot create collection collection. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 4. This allows a maximum of 4 to be created. Value of numShards is 2, value of nrtReplicas is 10, value of tlogReplicas is 0 and value of pullReplicas is 0. This requires 20 shards to be created (higher than the allowed number) [junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.buildReplicaPositions(CreateCollectionCmd.java:330) [junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:123) [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:257) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 908687 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=49 [junit4] 2> 908689 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas [junit4] 2> 908695 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 908876 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deleteCollectionOnlyInZk [junit4] 2> 908877 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[846794C8C1186B6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 908891 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 908946 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=55 [junit4] 2> 908947 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 908947 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 908947 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=onlyinzk&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 908956 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 908957 INFO (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection onlyinzk [junit4] 2> 909076 INFO (OverseerStateUpdate-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"onlyinzk", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"onlyinzk_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:37200/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 909080 INFO (OverseerStateUpdate-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"onlyinzk", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"onlyinzk_shard2_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:47324/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 909319 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.u.TestInjection Inject random core creation delay of 3s [junit4] 2> 909322 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.u.TestInjection Inject random core creation delay of 3s [junit4] 2> 912320 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica_n3&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 912323 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica_n1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 912338 INFO (zkCallback-870-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 913350 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 913379 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 913392 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.s.IndexSchema [onlyinzk_shard1_replica_n1] Schema name=minimal [junit4] 2> 913395 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.s.IndexSchema [onlyinzk_shard2_replica_n3] Schema name=minimal [junit4] 2> 913401 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 913401 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'onlyinzk_shard2_replica_n3' using configuration from collection onlyinzk, trusted=true [junit4] 2> 913402 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.onlyinzk.shard2.replica_n3' (registry 'solr.core.onlyinzk.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 913402 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 913402 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.SolrCore [[onlyinzk_shard2_replica_n3] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node4/onlyinzk_shard2_replica_n3], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node4/onlyinzk_shard2_replica_n3/data/] [junit4] 2> 913413 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 913413 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'onlyinzk_shard1_replica_n1' using configuration from collection onlyinzk, trusted=true [junit4] 2> 913413 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.onlyinzk.shard1.replica_n1' (registry 'solr.core.onlyinzk.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 913414 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 913414 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore [[onlyinzk_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node3/onlyinzk_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node3/onlyinzk_shard1_replica_n1/data/] [junit4] 2> 913683 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 913683 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 913684 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 913684 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 913693 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 913693 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 913695 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 913695 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 913697 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@28f368a1[onlyinzk_shard1_replica_n1] main] [junit4] 2> 913698 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 913699 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 913700 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 913700 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1602214161053384704 [junit4] 2> 913700 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@50f8da08[onlyinzk_shard2_replica_n3] main] [junit4] 2> 913701 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 913702 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 913702 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 913703 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1602214161056530432 [junit4] 2> 913703 INFO (searcherExecutor-1469-thread-1-processing-n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1 c:onlyinzk s:shard1 r:core_node2) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore [onlyinzk_shard1_replica_n1] Registered new searcher Searcher@28f368a1[onlyinzk_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 913717 INFO (searcherExecutor-1468-thread-1-processing-n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3 c:onlyinzk s:shard2 r:core_node4) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.SolrCore [onlyinzk_shard2_replica_n3] Registered new searcher Searcher@50f8da08[onlyinzk_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 913723 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/onlyinzk/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 913723 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/onlyinzk/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 913727 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 913727 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 913727 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:47324/solr/onlyinzk_shard2_replica_n3/ [junit4] 2> 913727 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 913727 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.SyncStrategy http://127.0.0.1:47324/solr/onlyinzk_shard2_replica_n3/ has no replicas [junit4] 2> 913727 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 913732 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:47324/solr/onlyinzk_shard2_replica_n3/ shard2 [junit4] 2> 913748 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 913748 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 913748 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37200/solr/onlyinzk_shard1_replica_n1/ [junit4] 2> 913748 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 913749 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:37200/solr/onlyinzk_shard1_replica_n1/ has no replicas [junit4] 2> 913749 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 913753 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37200/solr/onlyinzk_shard1_replica_n1/ shard1 [junit4] 2> 913856 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 913861 INFO (qtp609588498-7088) [n:127.0.0.1:37200_solr c:onlyinzk s:shard1 r:core_node2 x:onlyinzk_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica_n1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4539 [junit4] 2> 913893 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 913896 INFO (qtp716349888-7111) [n:127.0.0.1:47324_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica_n3&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4578 [junit4] 2> 913900 INFO (qtp716349888-7115) [n:127.0.0.1:47324_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> 913996 INFO (zkCallback-870-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 913996 INFO (zkCallback-878-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 914900 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=onlyinzk&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=5953 [junit4] 2> 914902 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 914902 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 914902 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteCollectionOnlyInZk [junit4] IGNOR/A 0.00s J1 | CollectionsAPIDistributedZkTest.testCollectionsAPI [junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028)) [junit4] 2> 914970 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNodeSet [junit4] 2> 914972 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[846794C8C1186B6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 914974 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 914980 INFO (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 914981 INFO (qtp609588498-7084) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard1.replica_n1, tag=null [junit4] 2> 914981 INFO (qtp716349888-7118) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard2.replica_n3, tag=null [junit4] 2> 914981 INFO (qtp609588498-7084) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@321962f: rootName = null, domain = solr.core.onlyinzk.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.onlyinzk.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@f2cce87 [junit4] 2> 914981 INFO (qtp716349888-7118) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@62fe1051: rootName = null, domain = solr.core.onlyinzk.shard2.replica_n3, service url = null, agent id = null] for registry solr.core.onlyinzk.shard2.replica_n3 / com.codahale.metrics.MetricRegistry@797dd7e1 [junit4] 2> 915005 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 915033 INFO (qtp609588498-7084) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore [onlyinzk_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@48ac74ae [junit4] 2> 915033 INFO (qtp609588498-7084) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard1.replica_n1, tag=48ac74ae [junit4] 2> 915033 INFO (qtp609588498-7084) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.onlyinzk.shard1.leader, tag=48ac74ae [junit4] 2> 915057 INFO (qtp716349888-7118) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.c.SolrCore [onlyinzk_shard2_replica_n3] CLOSING SolrCore org.apache.solr.core.SolrCore@17785136 [junit4] 2> 915057 INFO (qtp716349888-7118) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard2.replica_n3, tag=17785136 [junit4] 2> 915058 INFO (qtp716349888-7118) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.onlyinzk.shard2.leader, tag=17785136 [junit4] 2> 915070 INFO (qtp609588498-7084) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/onlyinzk/terms/shard1 to Terms{values={}, version=1} [junit4] 2> 915070 INFO (qtp716349888-7118) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/onlyinzk/terms/shard2 to Terms{values={}, version=1} [junit4] 2> 915072 INFO (qtp609588498-7084) [n:127.0.0.1:37200_solr x:onlyinzk_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=onlyinzk_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=91 [junit4] 2> 915075 INFO (qtp716349888-7118) [n:127.0.0.1:47324_solr x:onlyinzk_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=onlyinzk_shard2_replica_n3&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=94 [junit4] 2> 915180 INFO (zkCallback-870-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 915181 INFO (zkCallback-878-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 915799 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=825 [junit4] 2> 915800 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=nodeset_collection&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=http://127.0.0.1:44803/solr,http://127.0.0.1:44803/solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 915803 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 915804 INFO (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection nodeset_collection [junit4] 2> 915805 WARN (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (nodeset_collection) without cores. [junit4] 2> 916088 INFO (qtp716349888-7115) [n:127.0.0.1:47324_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> 916093 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=nodeset_collection&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=http://127.0.0.1:44803/solr,http://127.0.0.1:44803/solr&wt=javabin&version=2} status=0 QTime=293 [junit4] 2> 916095 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNodeSet [junit4] 2> 916253 INFO (TEST-CollectionsAPIDistributedZkTest.testSpecificConfigsets-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSpecificConfigsets [junit4] 2> 916254 INFO (TEST-CollectionsAPIDistributedZkTest.testSpecificConfigsets-seed#[846794C8C1186B6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 916256 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=nodeset_collection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 916259 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000012 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 916259 INFO (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 917078 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=nodeset_collection&action=DELETE&wt=javabin&version=2} status=0 QTime=822 [junit4] 2> 917083 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf2&name=withconfigset2&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 917086 INFO (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection withconfigset2 [junit4] 2> 917087 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000014 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 917300 INFO (OverseerStateUpdate-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"withconfigset2", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"withconfigset2_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:37200/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 917526 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.u.TestInjection Inject random core creation delay of 3s [junit4] 2> 920668 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf2&newCollection=true&name=withconfigset2_shard1_replica_n1&action=CREATE&numShards=1&collection=withconfigset2&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 920692 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 920728 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.IndexSchema [withconfigset2_shard1_replica_n1] Schema name=minimal [junit4] 2> 920732 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 920732 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'withconfigset2_shard1_replica_n1' using configuration from collection withconfigset2, trusted=true [junit4] 2> 920732 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.withconfigset2.shard1.replica_n1' (registry 'solr.core.withconfigset2.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c74e840 [junit4] 2> 920733 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 920733 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrCore [[withconfigset2_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node3/withconfigset2_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_846794C8C1186B6A-001/tempDir-001/node3/withconfigset2_shard1_replica_n1/data/] [junit4] 2> 920805 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 920805 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 920806 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 920806 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 920808 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2eef0400[withconfigset2_shard1_replica_n1] main] [junit4] 2> 920810 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2 [junit4] 2> 920810 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2 [junit4] 2> 920811 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 920811 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1602214168509808640 [junit4] 2> 920814 INFO (searcherExecutor-1478-thread-1-processing-n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1 c:withconfigset2 s:shard1 r:core_node2) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrCore [withconfigset2_shard1_replica_n1] Registered new searcher Searcher@2eef0400[withconfigset2_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 920820 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/withconfigset2/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 920826 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 920826 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 920826 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37200/solr/withconfigset2_shard1_replica_n1/ [junit4] 2> 920827 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 920827 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:37200/solr/withconfigset2_shard1_replica_n1/ has no replicas [junit4] 2> 920827 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 920831 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37200/solr/withconfigset2_shard1_replica_n1/ shard1 [junit4] 2> 920986 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 920990 INFO (qtp609588498-7087) [n:127.0.0.1:37200_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf2&newCollection=true&name=withconfigset2_shard1_replica_n1&action=CREATE&numShards=1&collection=withconfigset2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3464 [junit4] 2> 920998 INFO (qtp716349888-7115) [n:127.0.0.1:47324_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> 921001 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000016 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 921090 INFO (zkCallback-878-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4]) [junit4] 2> 922108 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf2&name=withconfigset2&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=5025 [junit4] 2> 922111 INFO (TEST-CollectionsAPIDistributedZkTest.testSpecificConfigsets-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSpecificConfigsets [junit4] 2> 922173 INFO (TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[846794C8C1186B6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreationAndDeletion [junit4] 2> 922174 INFO (TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[846794C8C1186B6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 922177 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=withconfigset2&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 922183 INFO (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 922184 INFO (qtp609588498-7089) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.withconfigset2.shard1.replica_n1, tag=null [junit4] 2> 922185 INFO (qtp609588498-7089) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@604399ae: rootName = null, domain = solr.core.withconfigset2.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.withconfigset2.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@3b5279e4 [junit4] 2> 922204 INFO (qtp609588498-7089) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrCore [withconfigset2_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@14da7365 [junit4] 2> 922204 INFO (qtp609588498-7089) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.withconfigset2.shard1.replica_n1, tag=14da7365 [junit4] 2> 922204 INFO (qtp609588498-7089) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.withconfigset2.shard1.leader, tag=14da7365 [junit4] 2> 922218 INFO (qtp609588498-7089) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/withconfigset2/terms/shard1 to Terms{values={}, version=1} [junit4] 2> 922222 INFO (qtp609588498-7089) [n:127.0.0.1:37200_solr x:withconfigset2_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=withconfigset2_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=37 [junit4] 2> 922327 INFO (zkCallback-878-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4]) [junit4] 2> 922946 INFO (qtp716349888-7117) [n:127.0.0.1:47324_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=withconfigset2&action=DELETE&wt=javabin&version=2} status=0 QTime=769 [junit4] 2> 922952 INFO (qtp716349888-7115) [n:127.0.0.1:47324_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=created_and_deleted&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 922954 INFO (OverseerThreadFactory-1451-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection created_and_deleted [junit4] 2> 922963 INFO (OverseerCollectionConfigSetProcessor-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000018 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 923170 INFO (OverseerStateUpdate-73908486718816266-127.0.0.1:37200_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"created_and_deleted", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"created_and_deleted_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:44803/solr";, [junit4] 2> [...truncated too long message...] 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 = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null1247097106 [copy] Copying 39 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null1247097106 [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null1247097106 resolve-example: 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 = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: resolve-server: 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 = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: 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 = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1478225339 [copy] Copying 247 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1478225339 [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1478225339 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 64ms :: artifacts dl 7ms --------------------------------------------------------------------- | | 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: 430 minutes 53 seconds Archiving artifacts WARN: No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? WARN: java.lang.InterruptedException: no matches found within 10000 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)
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
