Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19045/ Java: 32bit/jdk-9-ea+158 -client -XX:+UseSerialGC
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI Error Message: Error from server at http://127.0.0.1:38067/solr/awhollynewcollection_0: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 510 </title> </head> <body> <h2>HTTP ERROR: 510</h2> <p>Problem accessing /solr/awhollynewcollection_0/select. Reason: <pre> {metadata={error-class=org.apache.solr.common.SolrException,root-error-class=org.apache.solr.common.SolrException},msg={"awhollynewcollection_0":7},code=510}</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html> Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38067/solr/awhollynewcollection_0: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 510 </title> </head> <body> <h2>HTTP ERROR: 510</h2> <p>Problem accessing /solr/awhollynewcollection_0/select. Reason: <pre> {metadata={error-class=org.apache.solr.common.SolrException,root-error-class=org.apache.solr.common.SolrException},msg={"awhollynewcollection_0":7},code=510}</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html> at __randomizedtesting.SeedInfo.seed([60782D5DED1F0D73:280D59E9EB2C22E6]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:595) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:439) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:391) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1361) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1112) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1042) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:523) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:547) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.lucene.index.TestReadOnlyIndex.testReadOnlyIndex Error Message: access denied ("java.io.FilePermission" "/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J2/temp/readonlyindex10465907273009803065" "read") Stack Trace: java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J2/temp/readonlyindex10465907273009803065" "read") at __randomizedtesting.SeedInfo.seed([9CB91EFC6BB137C3:253CC5A2237279D1]:0) at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:471) at java.base/java.security.AccessController.checkPermission(AccessController.java:894) at java.base/java.lang.SecurityManager.checkPermission(SecurityManager.java:560) at java.base/java.lang.SecurityManager.checkRead(SecurityManager.java:899) at java.base/sun.nio.fs.UnixPath.checkRead(UnixPath.java:818) at java.base/sun.nio.fs.UnixFileSystemProvider.newDirectoryStream(UnixFileSystemProvider.java:395) at java.base/java.nio.file.Files.newDirectoryStream(Files.java:460) at org.apache.lucene.store.FSDirectory.listAll(FSDirectory.java:215) at org.apache.lucene.store.FSDirectory.listAll(FSDirectory.java:234) at org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:646) at org.apache.lucene.index.StandardDirectoryReader.open(StandardDirectoryReader.java:77) at org.apache.lucene.index.DirectoryReader.open(DirectoryReader.java:63) at org.apache.lucene.index.TestReadOnlyIndex.doTestReadOnlyIndex(TestReadOnlyIndex.java:81) at java.base/java.security.AccessController.doPrivileged(Native Method) at org.apache.lucene.util.LuceneTestCase.runWithRestrictedPermissions(LuceneTestCase.java:2815) at org.apache.lucene.index.TestReadOnlyIndex.testReadOnlyIndex(TestReadOnlyIndex.java:69) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:547) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 376 lines...] [junit4] Suite: org.apache.lucene.index.TestReadOnlyIndex [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReadOnlyIndex -Dtests.method=testReadOnlyIndex -Dtests.seed=9CB91EFC6BB137C3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mas -Dtests.timezone=Etc/GMT-11 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.01s J2 | TestReadOnlyIndex.testReadOnlyIndex <<< [junit4] > Throwable #1: java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J2/temp/readonlyindex10465907273009803065" "read") [junit4] > at __randomizedtesting.SeedInfo.seed([9CB91EFC6BB137C3:253CC5A2237279D1]:0) [junit4] > at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:471) [junit4] > at java.base/java.security.AccessController.checkPermission(AccessController.java:894) [junit4] > at java.base/java.lang.SecurityManager.checkPermission(SecurityManager.java:560) [junit4] > at java.base/java.lang.SecurityManager.checkRead(SecurityManager.java:899) [junit4] > at java.base/sun.nio.fs.UnixPath.checkRead(UnixPath.java:818) [junit4] > at java.base/sun.nio.fs.UnixFileSystemProvider.newDirectoryStream(UnixFileSystemProvider.java:395) [junit4] > at java.base/java.nio.file.Files.newDirectoryStream(Files.java:460) [junit4] > at org.apache.lucene.store.FSDirectory.listAll(FSDirectory.java:215) [junit4] > at org.apache.lucene.store.FSDirectory.listAll(FSDirectory.java:234) [junit4] > at org.apache.lucene.index.SegmentInfos$FindSegmentsFile.run(SegmentInfos.java:646) [junit4] > at org.apache.lucene.index.StandardDirectoryReader.open(StandardDirectoryReader.java:77) [junit4] > at org.apache.lucene.index.DirectoryReader.open(DirectoryReader.java:63) [junit4] > at org.apache.lucene.index.TestReadOnlyIndex.doTestReadOnlyIndex(TestReadOnlyIndex.java:81) [junit4] > at java.base/java.security.AccessController.doPrivileged(Native Method) [junit4] > at org.apache.lucene.util.LuceneTestCase.runWithRestrictedPermissions(LuceneTestCase.java:2815) [junit4] > at org.apache.lucene.index.TestReadOnlyIndex.testReadOnlyIndex(TestReadOnlyIndex.java:69) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:547) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false): {fieldname=DFR I(n)LZ(0.3)}, locale=mas, timezone=Etc/GMT-11 [junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=112739072,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestDirectPacked, TestSetOnce, TestMergedIterator, TestAssertions, TestDocument, TestHighCompressionMode, TestMultiFields, TestOneMergeWrappingMergePolicy, TestTermScorer, TestEarlyTermination, Test2BNumericDocValues, TestFastCompressionMode, TestDocValuesRewriteMethod, TestConsistentFieldNumbers, TestSpanCollection, TestIndexWriterMaxDocs, TestSameTokenSamePosition, TestOfflineSorter, TestSparseFixedBitDocIdSet, TestBufferedIndexInput, TestStressAdvance, TestOperations, TestStringMSBRadixSorter, TestStressIndexing2, TestRoaringDocIdSet, TestReadOnlyIndex] [junit4] Completed [54/448 (1!)] on J2 in 0.98s, 1 test, 1 error <<< FAILURES! [...truncated 11455 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/init-core-data-001 [junit4] 2> 939742 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 939744 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 939744 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001 [junit4] 2> 939745 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 939745 INFO (Thread-1349) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 939745 INFO (Thread-1349) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 939845 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:39144 [junit4] 2> 939849 INFO (jetty-launcher-1039-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 939849 INFO (jetty-launcher-1039-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 939849 INFO (jetty-launcher-1039-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 939849 INFO (jetty-launcher-1039-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 939851 INFO (jetty-launcher-1039-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b7a712{/solr,null,AVAILABLE} [junit4] 2> 939851 INFO (jetty-launcher-1039-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@113f111{/solr,null,AVAILABLE} [junit4] 2> 939851 INFO (jetty-launcher-1039-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8269be{/solr,null,AVAILABLE} [junit4] 2> 939851 INFO (jetty-launcher-1039-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1fd5ed2{/solr,null,AVAILABLE} [junit4] 2> 939853 INFO (jetty-launcher-1039-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@42634f{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 939853 INFO (jetty-launcher-1039-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b2fb78{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 939853 INFO (jetty-launcher-1039-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@19bcc4b{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-2) [ ] o.e.j.s.Server Started @??????ms [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-1) [ ] o.e.j.s.Server Started @??????ms [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=32903} [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42790} [junit4] 2> 939853 INFO (jetty-launcher-1039-thread-3) [ ] o.e.j.s.Server Started @??????ms [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45618} [junit4] 2> 939854 ERROR (jetty-launcher-1039-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 939854 ERROR (jetty-launcher-1039-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 939854 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 939854 ERROR (jetty-launcher-1039-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 939855 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-25T07:18:40.804730Z [junit4] 2> 939855 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 939855 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 939856 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 939856 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-25T07:18:40.805023Z [junit4] 2> 939856 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 939856 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-25T07:18:40.805123Z [junit4] 2> 939855 INFO (jetty-launcher-1039-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@162bb01{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 939858 INFO (jetty-launcher-1039-thread-4) [ ] o.e.j.s.Server Started @??????ms [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38067} [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 939868 ERROR (jetty-launcher-1039-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-25T07:18:40.817807Z [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 939868 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 939870 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 939877 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39144/solr [junit4] 2> 939877 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39144/solr [junit4] 2> 939880 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39144/solr [junit4] 2> 939881 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39144/solr [junit4] 2> 939896 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 939896 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 939896 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 939897 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 939897 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42790_solr [junit4] 2> 939897 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45618_solr [junit4] 2> 939897 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38067_solr [junit4] 2> 939897 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32903_solr [junit4] 2> 939898 INFO (zkCallback-1057-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 939898 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 939899 INFO (zkCallback-1061-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 939899 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 939899 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.c.Overseer Overseer (id=97518034662064137-127.0.0.1:42790_solr-n_0000000000) starting [junit4] 2> 939905 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42790_solr [junit4] 2> 939905 INFO (zkCallback-1057-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 939905 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 939906 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 939927 INFO (zkCallback-1061-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 940061 INFO (jetty-launcher-1039-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node2/. [junit4] 2> 940068 INFO (jetty-launcher-1039-thread-4) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node4/. [junit4] 2> 940084 INFO (jetty-launcher-1039-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node1/. [junit4] 2> 940120 INFO (jetty-launcher-1039-thread-3) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node3/. [junit4] 2> 940691 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 940691 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39144/solr ready [junit4] 2> 940722 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas [junit4] 2> 940723 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 940727 INFO (qtp27934190-6351) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 940728 INFO (OverseerThreadFactory-2583-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection collection [junit4] 2> 940728 WARN (OverseerThreadFactory-2583-thread-1) [ ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 10 on collection collection is higher than or equal to 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> 940729 ERROR (OverseerThreadFactory-2583-thread-1) [ ] o.a.s.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 and value of replicationFactor is 10. This requires 20 shards to be created (higher than the allowed number) [junit4] 2> at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155) [junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:224) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:463) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 940733 INFO (qtp27934190-6351) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=5 [junit4] 2> 940733 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas [junit4] 2> 940753 INFO (TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deletePartiallyCreatedCollection [junit4] 2> 940753 INFO (TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 940757 INFO (qtp27934190-6358) [ ] o.a.s.u.TestInjection Inject random core creation delay of 6s [junit4] 2> 942729 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 946757 INFO (qtp27934190-6358) [ ] o.a.s.h.a.CoreAdminOperation core create command dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2 [junit4] 2> 946759 INFO (OverseerStateUpdate-97518034662064137-127.0.0.1:42790_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 947764 INFO (qtp27934190-6358) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 947777 INFO (qtp27934190-6358) [ ] o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal [junit4] 2> 947778 INFO (qtp27934190-6358) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 947779 INFO (qtp27934190-6358) [ ] o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard1_replica1' using configuration from collection halfdeletedcollection [junit4] 2> 947779 INFO (qtp27934190-6358) [ ] o.a.s.c.SolrCore [[halfdeletedcollection_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node1/halfdeletedcollection_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-002/] [junit4] 2> 947960 INFO (qtp27934190-6358) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 947960 INFO (qtp27934190-6358) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 947962 INFO (qtp27934190-6358) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 947962 INFO (qtp27934190-6358) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 947963 INFO (qtp27934190-6358) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f357[halfdeletedcollection_shard1_replica1] main] [junit4] 2> 947963 INFO (qtp27934190-6358) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 947964 INFO (qtp27934190-6358) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 947964 INFO (qtp27934190-6358) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 947964 INFO (searcherExecutor-2592-thread-1) [ ] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] Registered new searcher Searcher@2f357[halfdeletedcollection_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 947964 INFO (qtp27934190-6358) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560288563207077888 [junit4] 2> 947968 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 947968 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 947968 INFO (qtp27934190-6358) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42790/solr/halfdeletedcollection_shard1_replica1/ [junit4] 2> 947968 INFO (qtp27934190-6358) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 947968 INFO (qtp27934190-6358) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:42790/solr/halfdeletedcollection_shard1_replica1/ has no replicas [junit4] 2> 947968 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 947970 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42790/solr/halfdeletedcollection_shard1_replica1/ shard2 [junit4] 2> 948120 INFO (qtp27934190-6358) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 948123 INFO (qtp27934190-6358) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2} status=0 QTime=7367 [junit4] 2> 948124 INFO (qtp27934190-6362) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=halfdeletedcollection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 948125 INFO (OverseerThreadFactory-2583-thread-2) [ ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 948126 INFO (qtp27934190-6351) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.halfdeletedcollection.shard1.replica1 [junit4] 2> 948127 INFO (qtp27934190-6351) [ ] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@1c847 [junit4] 2> 948128 INFO (qtp27934190-6351) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.halfdeletedcollection.shard1.replica1 [junit4] 2> 948132 INFO (qtp27934190-6351) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=6 [junit4] 2> 948835 INFO (qtp27934190-6362) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0 QTime=711 [junit4] 2> 948836 INFO (qtp27934190-6332) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 948836 INFO (qtp27934190-6332) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 948836 INFO (qtp27934190-6335) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 948837 INFO (OverseerThreadFactory-2583-thread-3) [ ] o.a.s.c.CreateCollectionCmd Create collection halfdeletedcollection [junit4] 2> 948837 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 949041 INFO (qtp32392637-6353) [ ] o.a.s.u.TestInjection Inject random core creation delay of 6s [junit4] 2> 949041 INFO (qtp1113348-6357) [ ] o.a.s.u.TestInjection Inject random core creation delay of 6s [junit4] 2> 955041 INFO (qtp32392637-6353) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard2_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2 [junit4] 2> 955041 INFO (qtp1113348-6357) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2 [junit4] 2> 955044 INFO (zkCallback-1061-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 955044 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 955146 INFO (zkCallback-1061-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 955146 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 956048 INFO (qtp1113348-6357) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 956048 INFO (qtp32392637-6353) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 956064 INFO (qtp1113348-6357) [ ] o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal [junit4] 2> 956064 INFO (qtp32392637-6353) [ ] o.a.s.s.IndexSchema [halfdeletedcollection_shard2_replica1] Schema name=minimal [junit4] 2> 956065 INFO (qtp1113348-6357) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 956065 INFO (qtp32392637-6353) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 956066 INFO (qtp1113348-6357) [ ] o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard1_replica1' using configuration from collection halfdeletedcollection [junit4] 2> 956066 INFO (qtp32392637-6353) [ ] o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard2_replica1' using configuration from collection halfdeletedcollection [junit4] 2> 956067 INFO (qtp32392637-6353) [ ] o.a.s.c.SolrCore [[halfdeletedcollection_shard2_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node2/halfdeletedcollection_shard2_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node2/./halfdeletedcollection_shard2_replica1/data/] [junit4] 2> 956067 INFO (qtp1113348-6357) [ ] o.a.s.c.SolrCore [[halfdeletedcollection_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node4/halfdeletedcollection_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node4/./halfdeletedcollection_shard1_replica1/data/] [junit4] 2> 956291 INFO (qtp1113348-6357) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 956291 INFO (qtp1113348-6357) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 956295 INFO (qtp32392637-6353) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 956296 INFO (qtp32392637-6353) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 956296 INFO (qtp1113348-6357) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 956297 INFO (qtp1113348-6357) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 956297 INFO (qtp1113348-6357) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a5801[halfdeletedcollection_shard1_replica1] main] [junit4] 2> 956297 INFO (qtp1113348-6357) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 956298 INFO (qtp1113348-6357) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 956298 INFO (qtp1113348-6357) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 956298 INFO (searcherExecutor-2597-thread-1) [ ] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] Registered new searcher Searcher@4a5801[halfdeletedcollection_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 956298 INFO (qtp1113348-6357) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560288571945910272 [junit4] 2> 956301 INFO (qtp32392637-6353) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 956301 INFO (qtp32392637-6353) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 956301 INFO (qtp32392637-6353) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16d08e7[halfdeletedcollection_shard2_replica1] main] [junit4] 2> 956302 INFO (qtp32392637-6353) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 956302 INFO (qtp32392637-6353) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 956302 INFO (qtp32392637-6353) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 956303 INFO (searcherExecutor-2598-thread-1) [ ] o.a.s.c.SolrCore [halfdeletedcollection_shard2_replica1] Registered new searcher Searcher@16d08e7[halfdeletedcollection_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 956303 INFO (qtp32392637-6353) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560288571951153152 [junit4] 2> 956303 INFO (qtp1113348-6357) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 956303 INFO (qtp1113348-6357) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 956303 INFO (qtp1113348-6357) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:38067/solr/halfdeletedcollection_shard1_replica1/ [junit4] 2> 956303 INFO (qtp1113348-6357) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 956303 INFO (qtp1113348-6357) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:38067/solr/halfdeletedcollection_shard1_replica1/ has no replicas [junit4] 2> 956303 INFO (qtp1113348-6357) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 956304 INFO (qtp1113348-6357) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38067/solr/halfdeletedcollection_shard1_replica1/ shard1 [junit4] 2> 956306 INFO (qtp32392637-6353) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 956306 INFO (qtp32392637-6353) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 956306 INFO (qtp32392637-6353) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:32903/solr/halfdeletedcollection_shard2_replica1/ [junit4] 2> 956307 INFO (qtp32392637-6353) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 956307 INFO (qtp32392637-6353) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:32903/solr/halfdeletedcollection_shard2_replica1/ has no replicas [junit4] 2> 956307 INFO (qtp32392637-6353) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 956308 INFO (qtp32392637-6353) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:32903/solr/halfdeletedcollection_shard2_replica1/ shard2 [junit4] 2> 956410 INFO (zkCallback-1061-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 956410 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 956455 INFO (qtp1113348-6357) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 956458 INFO (qtp1113348-6357) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2} status=0 QTime=7417 [junit4] 2> 956459 INFO (qtp32392637-6353) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 956463 INFO (qtp32392637-6353) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard2_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2} status=0 QTime=7422 [junit4] 2> 956464 INFO (qtp27934190-6335) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 956560 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 956560 INFO (zkCallback-1061-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 956839 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 957465 INFO (qtp27934190-6335) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=8628 [junit4] 2> 957465 INFO (qtp27934190-6351) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 957466 INFO (qtp27934190-6351) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 957466 INFO (TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deletePartiallyCreatedCollection [junit4] 2> 957486 INFO (TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxNodesPerShard [junit4] 2> 957487 INFO (TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 957487 INFO (qtp27934190-6362) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=halfdeletedcollection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 957488 INFO (OverseerThreadFactory-2583-thread-4) [ ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 957489 INFO (qtp32392637-6355) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.halfdeletedcollection.shard2.replica1 [junit4] 2> 957490 INFO (qtp32392637-6355) [ ] o.a.s.c.SolrCore [halfdeletedcollection_shard2_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@45c241 [junit4] 2> 957490 INFO (qtp1113348-6359) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.halfdeletedcollection.shard1.replica1 [junit4] 2> 957491 INFO (qtp1113348-6359) [ ] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@23282e [junit4] 2> 957493 INFO (qtp32392637-6355) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.halfdeletedcollection.shard2.replica1 [junit4] 2> 957493 INFO (qtp1113348-6359) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.halfdeletedcollection.shard1.replica1 [junit4] 2> 957505 INFO (qtp1113348-6359) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=15 [junit4] 2> 957505 INFO (qtp32392637-6355) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=halfdeletedcollection_shard2_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=15 [junit4] 2> 958207 INFO (qtp27934190-6362) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0 QTime=719 [junit4] 2> 958209 INFO (qtp27934190-6332) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&name=oversharded&action=CREATE&numShards=3&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 958210 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.CreateCollectionCmd Create collection oversharded [junit4] 2> 958210 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 958210 ERROR (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.OverseerCollectionMessageHandler Collection: oversharded operation: create failed:org.apache.solr.common.SolrException: Cannot create collection oversharded. 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 3 and value of replicationFactor is 2. This requires 6 shards to be created (higher than the allowed number) [junit4] 2> at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155) [junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:224) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:463) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 958213 INFO (qtp27934190-6332) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=conf&name=oversharded&action=CREATE&numShards=3&wt=javabin&version=2} status=400 QTime=4 [junit4] 2> 958213 INFO (TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxNodesPerShard [junit4] 2> 958236 INFO (TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMissingRequiredParameters [junit4] 2> 958237 INFO (TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 958238 INFO (qtp27934190-6335) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 958239 ERROR (qtp27934190-6335) [ ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Missing required parameter: name [junit4] 2> at org.apache.solr.common.params.RequiredSolrParams.getParams(RequiredSolrParams.java:90) [junit4] 2> at org.apache.solr.common.params.SolrParams.getAll(SolrParams.java:461) [junit4] 2> at org.apache.solr.common.params.SolrParams.getAll(SolrParams.java:478) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler$CollectionOperation.lambda$static$0(CollectionsHandler.java:394) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler$CollectionOperation.execute(CollectionsHandler.java:888) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:226) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:213) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:171) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:747) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 958239 INFO (qtp27934190-6335) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=1 [junit4] 2> 958239 INFO (TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMissingRequiredParameters [junit4] 2> 958262 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNodeSet [junit4] 2> 958263 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 958264 INFO (qtp27934190-6351) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=http://127.0.0.1:38067/solr,http://127.0.0.1:38067/solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 958265 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.CreateCollectionCmd Create collection nodeset_collection [junit4] 2> 958265 WARN (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (nodeset_collection) without cores. [junit4] 2> 958265 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 958469 INFO (qtp27934190-6351) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 958469 INFO (qtp27934190-6351) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=http://127.0.0.1:38067/solr,http://127.0.0.1:38067/solr&wt=javabin&version=2} status=0 QTime=205 [junit4] 2> 958470 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNodeSet [junit4] 2> 958492 INFO (TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZeroNumShards [junit4] 2> 958493 INFO (TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 958494 INFO (qtp27934190-6362) [ ] 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> 958495 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 958495 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 959197 INFO (qtp27934190-6362) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=nodeset_collection&action=DELETE&wt=javabin&version=2} status=0 QTime=703 [junit4] 2> 959198 INFO (qtp27934190-6332) [ ] 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> 959199 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.CreateCollectionCmd Create collection acollection [junit4] 2> 959199 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 959200 ERROR (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.OverseerCollectionMessageHandler Collection: acollection operation: create failed:org.apache.solr.common.SolrException: numShards must be > 0 [junit4] 2> at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:124) [junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:224) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:463) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 959202 INFO (qtp27934190-6332) [ ] 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=3 [junit4] 2> 959202 INFO (TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZeroNumShards [junit4] 2> 959223 INFO (TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNoCollectionSpecified [junit4] 2> 959224 INFO (TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 959226 INFO (qtp27934190-6335) [ ] o.a.s.u.TestInjection Inject random core creation delay of 6s [junit4] 2> 961200 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 965226 INFO (qtp27934190-6335) [ ] o.a.s.h.a.CoreAdminOperation core create command dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-003&collection.configName=conf&name=corewithnocollection&action=CREATE&numShards=1&collection=&wt=javabin&version=2 [junit4] 2> 965228 INFO (OverseerStateUpdate-97518034662064137-127.0.0.1:42790_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 966234 INFO (qtp27934190-6335) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 966238 INFO (qtp27934190-6335) [ ] o.a.s.s.IndexSchema [corewithnocollection] Schema name=minimal [junit4] 2> 966239 INFO (qtp27934190-6335) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 966239 INFO (qtp27934190-6335) [ ] o.a.s.c.CoreContainer Creating SolrCore 'corewithnocollection' using configuration from collection corewithnocollection [junit4] 2> 966240 INFO (qtp27934190-6335) [ ] o.a.s.c.SolrCore [[corewithnocollection] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node1/corewithnocollection], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-003/] [junit4] 2> 966428 INFO (qtp27934190-6335) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 966429 INFO (qtp27934190-6335) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 966431 INFO (qtp27934190-6335) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 966431 INFO (qtp27934190-6335) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 966431 INFO (qtp27934190-6335) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6eb40c[corewithnocollection] main] [junit4] 2> 966432 INFO (qtp27934190-6335) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 966432 INFO (qtp27934190-6335) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 966432 INFO (qtp27934190-6335) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 966432 INFO (searcherExecutor-2607-thread-1) [ ] o.a.s.c.SolrCore [corewithnocollection] Registered new searcher Searcher@6eb40c[corewithnocollection] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 966432 INFO (qtp27934190-6335) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560288582572179456 [junit4] 2> 966436 INFO (qtp27934190-6335) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 966436 INFO (qtp27934190-6335) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 966436 INFO (qtp27934190-6335) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42790/solr/corewithnocollection/ [junit4] 2> 966436 INFO (qtp27934190-6335) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 966437 INFO (qtp27934190-6335) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:42790/solr/corewithnocollection/ has no replicas [junit4] 2> 966437 INFO (qtp27934190-6335) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 966438 INFO (qtp27934190-6335) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42790/solr/corewithnocollection/ shard1 [junit4] 2> 966589 INFO (qtp27934190-6335) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 966592 INFO (qtp27934190-6335) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-003&collection.configName=conf&name=corewithnocollection&action=CREATE&numShards=1&collection=&wt=javabin&version=2} status=0 QTime=7366 [junit4] 2> 966593 INFO (qtp27934190-6351) [ ] o.a.s.u.TestInjection Inject random core creation delay of 6s [junit4] 2> 972593 INFO (qtp27934190-6351) [ ] o.a.s.h.a.CoreAdminOperation core create command dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-003&collection.configName=conf&name=corewithnocollection2&action=CREATE&numShards=1&wt=javabin&version=2 [junit4] 2> 972595 INFO (OverseerStateUpdate-97518034662064137-127.0.0.1:42790_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 973601 INFO (qtp27934190-6351) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 973615 INFO (qtp27934190-6351) [ ] o.a.s.s.IndexSchema [corewithnocollection2] Schema name=minimal [junit4] 2> 973616 INFO (qtp27934190-6351) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 973616 INFO (qtp27934190-6351) [ ] o.a.s.c.CoreContainer Creating SolrCore 'corewithnocollection2' using configuration from collection corewithnocollection2 [junit4] 2> 973617 INFO (qtp27934190-6351) [ ] o.a.s.c.SolrCore [[corewithnocollection2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node1/corewithnocollection2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-003/] [junit4] 2> 973826 INFO (qtp27934190-6351) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 973826 INFO (qtp27934190-6351) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 973828 INFO (qtp27934190-6351) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 973828 INFO (qtp27934190-6351) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 973828 INFO (qtp27934190-6351) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a1f647[corewithnocollection2] main] [junit4] 2> 973829 INFO (qtp27934190-6351) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 973829 INFO (qtp27934190-6351) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 973829 INFO (qtp27934190-6351) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 973829 INFO (searcherExecutor-2612-thread-1) [ ] o.a.s.c.SolrCore [corewithnocollection2] Registered new searcher Searcher@1a1f647[corewithnocollection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 973829 INFO (qtp27934190-6351) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560288590328496128 [junit4] 2> 973833 INFO (qtp27934190-6351) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 973833 INFO (qtp27934190-6351) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 973833 INFO (qtp27934190-6351) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42790/solr/corewithnocollection2/ [junit4] 2> 973833 INFO (qtp27934190-6351) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 973833 INFO (qtp27934190-6351) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:42790/solr/corewithnocollection2/ has no replicas [junit4] 2> 973833 INFO (qtp27934190-6351) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 973835 INFO (qtp27934190-6351) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42790/solr/corewithnocollection2/ shard1 [junit4] 2> 973985 INFO (qtp27934190-6351) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 973989 INFO (qtp27934190-6351) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-003&collection.configName=conf&name=corewithnocollection2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=7396 [junit4] 2> 973990 INFO (TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNoCollectionSpecified [junit4] 2> 974020 INFO (TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[60782D5DED1F0D73]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreationAndDeletion [junit4] 2> 974021 INFO (TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[60782D5DED1F0D73]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 974022 INFO (qtp27934190-6362) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=corewithnocollection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 974023 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 974025 INFO (qtp27934190-6358) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.corewithnocollection [junit4] 2> 974026 INFO (qtp27934190-6358) [ ] o.a.s.c.SolrCore [corewithnocollection] CLOSING SolrCore org.apache.solr.core.SolrCore@39e6a7 [junit4] 2> 974029 INFO (qtp27934190-6358) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.corewithnocollection [junit4] 2> 974034 INFO (qtp27934190-6358) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=corewithnocollection&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=9 [junit4] 2> 974740 INFO (qtp27934190-6362) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=corewithnocollection&action=DELETE&wt=javabin&version=2} status=0 QTime=718 [junit4] 2> 974741 INFO (qtp27934190-6332) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=corewithnocollection2&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 974743 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 974743 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 974744 INFO (qtp27934190-6335) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.corewithnocollection2 [junit4] 2> 974744 INFO (qtp27934190-6335) [ ] o.a.s.c.SolrCore [corewithnocollection2] CLOSING SolrCore org.apache.solr.core.SolrCore@1cb3e2 [junit4] 2> 974746 INFO (qtp27934190-6335) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.corewithnocollection2 [junit4] 2> 974753 INFO (qtp27934190-6335) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=corewithnocollection2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=9 [junit4] 2> 975456 INFO (qtp27934190-6332) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=corewithnocollection2&action=DELETE&wt=javabin&version=2} status=0 QTime=714 [junit4] 2> 975456 INFO (qtp27934190-6351) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=created_and_deleted&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 975457 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.CreateCollectionCmd Create collection created_and_deleted [junit4] 2> 975457 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_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> 975660 INFO (qtp27934190-6358) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=created_and_deleted_shard1_replica1&action=CREATE&numShards=1&collection=created_and_deleted&shard=shard1&wt=javabin&version=2 [junit4] 2> 975762 INFO (zkCallback-1058-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4]) [junit4] 2> 976665 INFO (qtp27934190-6358) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 976669 INFO (qtp27934190-6358) [ ] o.a.s.s.IndexSchema [created_and_deleted_shard1_replica1] Schema name=minimal [junit4] 2> 976686 INFO (qtp27934190-6358) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 976687 INFO (qtp27934190-6358) [ ] o.a.s.c.CoreContainer Creating SolrCore 'created_and_deleted_shard1_replica1' using configuration from collection created_and_deleted [junit4] 2> 976687 INFO (qtp27934190-6358) [ ] o.a.s.c.SolrCore [[created_and_deleted_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node1/created_and_deleted_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001/tempDir-001/node1/./created_and_deleted_shard1_replica1/data/] [junit4] 2> 976873 INFO (qtp27934190-6358) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 976873 INFO (qtp27934190-6358) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 976879 INFO (qtp27934190-6358) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 976879 INFO (qtp27934190-6358) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 976879 INFO (qtp27934190-6358) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@de30b8[created_and_deleted_shard1_replica1] main] [junit4] 2> 976879 INFO (qtp27934190-6358) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 976880 INFO (qtp27934190-6358) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 976880 INFO (qtp27934190-6358) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 976880 INFO (searcherExecutor-2617-thread-1) [ ] o.a.s.c.SolrCore [created_and_deleted_shard1_replica1] Registered new searcher Searcher@de30b8[created_and_deleted_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 976880 INFO (qtp27934190-6358) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560288593527701504 [junit4] 2> 976884 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 976884 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 976884 INFO (qtp27934190-6358) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42790/solr/created_and_deleted_shard1_replica1/ [junit4] 2> 976884 INFO (qtp27934190-6358) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 976884 INFO (qtp27934190-6358) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:42790/solr/created_and_deleted_shard1_replica1/ has no replicas [junit4] 2> 976884 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 976885 INFO (qtp27934190-6358) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42790/solr/created_and_deleted_shard1_replica1/ shard1 [junit4] 2> 976987 INFO (zkCallback-1058-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4]) [junit4] 2> 977036 INFO (qtp27934190-6358) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 977039 INFO (qtp27934190-6358) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=created_and_deleted_shard1_replica1&action=CREATE&numShards=1&collection=created_and_deleted&shard=shard1&wt=javabin&version=2} status=0 QTime=1379 [junit4] 2> 977041 INFO (qtp27934190-6351) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 977138 INFO (zkCallback-1058-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4]) [junit4] 2> 977458 INFO (OverseerCollectionConfigSetProcessor-97518034662064137-127.0.0.1:42790_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000020 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 978041 INFO (qtp27934190-6351) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=created_and_deleted&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2584 [junit4] 2> 978041 INFO (qtp27934190-6362) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 978042 INFO (qtp27934190-6362) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 978042 INFO (qtp27934190-6332) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=created_and_deleted&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 978045 INFO (OverseerThreadFactory-2583-thread-5) [ ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 978046 INFO (qtp27934190-6335) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.created_and_deleted.shard1.replica1 [junit4] 2> 978047 INFO (qtp27934190-6335) [ ] o.a.s.c.SolrCore [created_and_deleted_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@341de0 [junit4] 2> 978050 INFO (qtp27934190-6335) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.created_and_deleted.shard1.replica1 [junit4] 2> 978062 INFO (qtp27934190-6335) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=created_and_deleted_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=16 [junit4] 2> 978765 INFO (qtp27934190-6332) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=created_and_deleted&action=DELETE&wt=javabin&version=2} status=0 QTime=723 [junit4] 2> 978766 INFO (qtp27934190-6358) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 97876 [...truncated too long message...] c4b{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15232635 [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@42634f{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1854710 [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@b2fb78{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1bb5709{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=9059694 [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-4) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=11062963 [junit4] 2> 1105529 INFO (jetty-closer-1040-thread-1) [ ] o.a.s.c.Overseer Overseer (id=97518034662064137-127.0.0.1:42790_solr-n_0000000000) closing [junit4] 2> 1105530 INFO (OverseerStateUpdate-97518034662064137-127.0.0.1:42790_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42790_solr [junit4] 2> 1105530 INFO (zkCallback-1094-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1105530 INFO (jetty-closer-1040-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1105530 INFO (zkCallback-1058-thread-9) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1105530 INFO (zkCallback-1061-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1105530 INFO (jetty-closer-1040-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@113f111{/solr,null,UNAVAILABLE} [junit4] 2> 1105531 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15a7422c73a000c, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1105531 INFO (zkCallback-1058-thread-9) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1105532 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15a7422c73a0011, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1107030 WARN (zkCallback-1061-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1107030 WARN (zkCallback-1094-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1107030 INFO (jetty-closer-1040-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1107031 INFO (jetty-closer-1040-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1107031 INFO (jetty-closer-1040-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@b7a712{/solr,null,UNAVAILABLE} [junit4] 2> 1107031 INFO (jetty-closer-1040-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1be3ed0{/solr,null,UNAVAILABLE} [junit4] 2> 1107032 WARN (zkCallback-1058-thread-10) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1107032 INFO (jetty-closer-1040-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1107032 INFO (jetty-closer-1040-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1fd5ed2{/solr,null,UNAVAILABLE} [junit4] 2> 1107032 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39144 39144 [junit4] 2> 1107068 INFO (Thread-1349) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39144 39144 [junit4] 2> 1107069 WARN (Thread-1349) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 26 /solr/aliases.json [junit4] 2> 26 /solr/clusterprops.json [junit4] 2> 23 /solr/configs/conf [junit4] 2> 5 /solr/security.json [junit4] 2> 4 /solr/configs/conf2 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 281 /solr/clusterstate.json [junit4] 2> 4 /solr/collections/nodes_used_collection/state.json [junit4] 2> 4 /solr/collections/addReplicaColl/state.json [junit4] 2> 4 /solr/collections/reloaded_collection/state.json [junit4] 2> 3 /solr/collections/awhollynewcollection_0/state.json [junit4] 2> 3 /solr/collections/acollectionafterbaddelete/state.json [junit4] 2> 2 /solr/collections/onlyinzk/state.json [junit4] 2> 2 /solr/overseer_elect/election/97518034662064136-127.0.0.1:45618_solr-n_0000000001 [junit4] 2> 2 /solr/collections/addReplicaColl/leader_elect/shard1/election/97518034662064137-core_node1-n_0000000000 [junit4] 2> 2 /solr/collections/halfdeletedcollection/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 490 /solr/collections [junit4] 2> 116 /solr/overseer/queue [junit4] 2> 70 /solr/overseer/collection-queue-work [junit4] 2> 59 /solr/live_nodes [junit4] 2> 24 /solr/overseer/queue-work [junit4] 2> [junit4] 2> 1107069 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[60782D5DED1F0D73]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_60782D5DED1F0D73-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {_version_=PostingsFormat(name=Memory), id=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=863, maxMBSortInHeap=6.804497733153536, sim=RandomSimilarity(queryNorm=false): {}, locale=as, timezone=Asia/Dacca [junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=85283608,total=231989248 [junit4] 2> NOTE: All tests run in this JVM: [TestMiniSolrCloudCluster, TestUpdate, IndexSchemaTest, TestSystemCollAutoCreate, TestCollectionAPI, TestSweetSpotSimilarityFactory, DOMUtilTest, TestSlowCompositeReaderWrapper, TestGraphMLResponseWriter, TestManagedSchemaAPI, TestSQLHandlerNonCloud, ShardRoutingCustomTest, TestConfigsApi, TestCursorMarkWithoutUniqueKey, DistributedSuggestComponentTest, TestOnReconnectListenerSupport, VersionInfoTest, AsyncCallRequestStatusResponseTest, TestNumericTerms32, CdcrReplicationDistributedZkTest, LoggingHandlerTest, HdfsChaosMonkeyNothingIsSafeTest, ZkCLITest, SolrMetricManagerTest, TestConfigSetProperties, JsonLoaderTest, BigEndianAscendingWordSerializerTest, SolrInfoMBeanTest, TestHdfsCloudBackupRestore, TemplateUpdateProcessorTest, ConcurrentDeleteAndCreateCollectionTest, UUIDUpdateProcessorFallbackTest, SolrGangliaReporterTest, IndexBasedSpellCheckerTest, TestTolerantUpdateProcessorRandomCloud, ClusterStateUpdateTest, TestFieldTypeCollectionResource, SolrIndexSplitterTest, CreateCollectionCleanupTest, HdfsRestartWhileUpdatingTest, DeleteLastCustomShardedReplicaTest, QueryParsingTest, MoreLikeThisHandlerTest, HdfsNNFailoverTest, DocValuesTest, DistributedExpandComponentTest, TestRebalanceLeaders, TestDocBasedVersionConstraints, TestExpandComponent, TestSurroundQueryParser, OpenCloseCoreStressTest, TestComplexPhraseLeadingWildcard, SpellingQueryConverterTest, ExitableDirectoryReaderTest, SpellCheckCollatorTest, DocumentBuilderTest, TestElisionMultitermQuery, TestClassicSimilarityFactory, TestUseDocValuesAsStored2, TermVectorComponentTest, TestCoreContainer, JSONWriterTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestFastLRUCache, TestMaxScoreQueryParser, FullHLLTest, TestDocumentBuilder, AlternateDirectoryTest, TestObjectReleaseTracker, HdfsTlogReplayBufferedWhileIndexingTest, BlockJoinFacetDistribTest, StressHdfsTest, TestSimpleTrackingShardHandler, TestConfigSetsAPIZkFailure, TestSolrDynamicMBean, TestInPlaceUpdatesDistrib, TestRuleBasedAuthorizationPlugin, TestSearchPerf, TestSystemIdResolver, SparseHLLTest, DistributedVersionInfoTest, TestDynamicFieldResource, SuggesterFSTTest, CdcrVersionReplicationTest, HttpPartitionTest, SampleTest, PKIAuthenticationIntegrationTest, ZkControllerTest, SuggestComponentContextFilterQueryTest, TestUniqueKeyFieldResource, TestLeaderInitiatedRecoveryThread, TestCustomDocTransformer, IndexSchemaRuntimeFieldTest, CollectionsAPIDistributedZkTest] [junit4] Completed [311/693 (1!)] on J1 in 167.34s, 20 tests, 1 error <<< FAILURES! [...truncated 52862 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
