Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3897/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
3 tests failed. FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Captured an uncaught exception in thread: Thread[id=4144, name=Thread-900, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4144, name=Thread-900, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:60511/xxv/j/collection2_shard4_replica2: java.io.FileNotFoundException: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-5-001/cores/collection2_shard4_replica2/data/tlog/tlog.0000000000000000000 (Too many open files) at __randomizedtesting.SeedInfo.seed([B3A356A9F3E3B30F]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627) 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.directUpdate(CloudSolrClient.java:813) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1256) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1127) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1057) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:177) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:641) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.FullSolrCloudDistribCmdsTest Error Message: 2 threads leaked from SUITE scope at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest: 1) Thread[id=4115, name=searcherExecutor-1967-thread-1, state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=3917, name=qtp504926603-3917, state=TIMED_WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at java.lang.Throwable.fillInStackTrace(Native Method) at java.lang.Throwable.fillInStackTrace(Throwable.java:783) at java.lang.Throwable.<init>(Throwable.java:250) at java.lang.Exception.<init>(Exception.java:54) at java.lang.InterruptedException.<init>(InterruptedException.java:57) at java.lang.Object.wait(Native Method) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.waitForEmptyQueue(ConcurrentUpdateSolrClient.java:657) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:589) at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:91) at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:243) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:95) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:849) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1955) at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:182) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:78) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2464) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:534) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest: 1) Thread[id=4115, name=searcherExecutor-1967-thread-1, state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=3917, name=qtp504926603-3917, state=TIMED_WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at java.lang.Throwable.fillInStackTrace(Native Method) at java.lang.Throwable.fillInStackTrace(Throwable.java:783) at java.lang.Throwable.<init>(Throwable.java:250) at java.lang.Exception.<init>(Exception.java:54) at java.lang.InterruptedException.<init>(InterruptedException.java:57) at java.lang.Object.wait(Native Method) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.waitForEmptyQueue(ConcurrentUpdateSolrClient.java:657) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:589) at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:91) at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:243) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:95) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:849) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1955) at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:182) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:78) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2464) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:534) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([B3A356A9F3E3B30F]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.FullSolrCloudDistribCmdsTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=4115, name=searcherExecutor-1967-thread-1, state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=3917, name=qtp504926603-3917, state=TIMED_WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at java.lang.Object.wait(Native Method) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.waitForEmptyQueue(ConcurrentUpdateSolrClient.java:657) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:589) at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:91) at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:243) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:95) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:849) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1955) at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:182) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:78) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2464) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:534) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=4115, name=searcherExecutor-1967-thread-1, state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=3917, name=qtp504926603-3917, state=TIMED_WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] at java.lang.Object.wait(Native Method) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.waitForEmptyQueue(ConcurrentUpdateSolrClient.java:657) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:589) at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:91) at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:243) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:95) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:849) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1955) at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:182) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:78) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2464) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:534) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([B3A356A9F3E3B30F]:0) Build Log: [...truncated 11219 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/init-core-data-001 [junit4] 2> 374410 WARN (SUITE-FullSolrCloudDistribCmdsTest-seed#[B3A356A9F3E3B30F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 374410 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[B3A356A9F3E3B30F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 374414 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[B3A356A9F3E3B30F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776) w/ MAC_OS_X supressed clientAuth [junit4] 2> 374414 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[B3A356A9F3E3B30F]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /xxv/j [junit4] 2> 374415 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 374415 INFO (Thread-825) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 374415 INFO (Thread-825) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 374519 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.ZkTestServer start zk server on port:60447 [junit4] 2> 374555 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 374560 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 374567 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 374570 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 374573 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 374576 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 374579 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 374582 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 374585 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 374588 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 374591 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 375598 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/control-001/cores/collection1 [junit4] 2> 375600 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 375602 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7aea829b{/xxv/j,null,AVAILABLE} [junit4] 2> 375603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2b6ca046{HTTP/1.1,[http/1.1]}{127.0.0.1:60455} [junit4] 2> 375603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server Started @381008ms [junit4] 2> 375603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/tempDir-001/control/data, hostContext=/xxv/j, hostPort=60455, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/control-001/cores} [junit4] 2> 375603 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 375603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 375603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 375603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 375603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-15T16:24:24.430Z [junit4] 2> 375608 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 375608 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/control-001/solr.xml [junit4] 2> 375618 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60447/solr [junit4] 2> 375662 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 375663 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60455_xxv%2Fj [junit4] 2> 375665 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.Overseer Overseer (id=97622102064824324-127.0.0.1:60455_xxv%2Fj-n_0000000000) starting [junit4] 2> 375680 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60455_xxv%2Fj [junit4] 2> 375683 INFO (zkCallback-740-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 375764 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/control-001/cores [junit4] 2> 375764 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 375768 INFO (OverseerStateUpdate-97622102064824324-127.0.0.1:60455_xxv%2Fj-n_0000000000) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 376790 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 376801 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 376851 WARN (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 376852 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 376880 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 376880 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 376880 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/control-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/control-001/cores/collection1/data/] [junit4] 2> 376881 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50c037d6 [junit4] 2> 376881 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 376886 WARN (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 376938 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 376938 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 376939 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 376939 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 376940 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=45.572265625, floorSegmentMB=0.2255859375, forceMergeDeletesPctAllowed=13.503314255470691, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12684793990289878 [junit4] 2> 376940 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72c52085[collection1] main] [junit4] 2> 376942 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 376942 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 376942 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 376943 INFO (searcherExecutor-1881-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72c52085[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 376944 INFO (coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj c:control_collection x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1561953635685892096 [junit4] 2> 376954 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 376954 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 376954 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60455/xxv/j/collection1/ [junit4] 2> 376954 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 376954 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60455/xxv/j/collection1/ has no replicas [junit4] 2> 376954 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 376961 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60455/xxv/j/collection1/ shard1 [junit4] 2> 377021 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 377023 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60447/solr ready [junit4] 2> 377023 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 377023 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 377067 INFO (coreZkRegister-1873-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj x:collection1 c:control_collection) [n:127.0.0.1:60455_xxv%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 377327 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-1-001/cores/collection1 [junit4] 2> 377328 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-1-001 [junit4] 2> 377329 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 377331 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2ce18a32{/xxv/j,null,AVAILABLE} [junit4] 2> 377331 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@21e8c5c7{HTTP/1.1,[http/1.1]}{127.0.0.1:60466} [junit4] 2> 377331 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server Started @382736ms [junit4] 2> 377331 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/xxv/j, hostPort=60466, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-1-001/cores} [junit4] 2> 377332 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 377332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 377332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 377332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 377332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-15T16:24:26.159Z [junit4] 2> 377337 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 377337 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-1-001/solr.xml [junit4] 2> 377364 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60447/solr [junit4] 2> 377383 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 377389 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 377394 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60466_xxv%2Fj [junit4] 2> 377398 INFO (zkCallback-740-thread-3-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 377400 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 377398 INFO (zkCallback-744-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 377502 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-1-001/cores [junit4] 2> 377503 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 377508 INFO (OverseerStateUpdate-97622102064824324-127.0.0.1:60455_xxv%2Fj-n_0000000000) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 377621 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 378532 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 378546 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 378589 WARN (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 378590 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 378617 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 378618 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 378618 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-1-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-1-001/cores/collection1/data/] [junit4] 2> 378618 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50c037d6 [junit4] 2> 378619 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 378623 WARN (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 379099 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 379099 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 379108 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 379108 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 379109 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=45.572265625, floorSegmentMB=0.2255859375, forceMergeDeletesPctAllowed=13.503314255470691, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12684793990289878 [junit4] 2> 379109 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e3c241e[collection1] main] [junit4] 2> 379111 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 379111 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 379112 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 379113 INFO (searcherExecutor-1892-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e3c241e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 379113 INFO (coreLoadExecutor-1891-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1561953637960253440 [junit4] 2> 379123 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 379123 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 379123 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60466/xxv/j/collection1/ [junit4] 2> 379123 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 379123 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60466/xxv/j/collection1/ has no replicas [junit4] 2> 379123 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 379126 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 379131 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60466/xxv/j/collection1/ shard2 [junit4] 2> 379248 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 379291 INFO (coreZkRegister-1886-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60466_xxv%2Fj c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 379399 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 379514 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-2-001/cores/collection1 [junit4] 2> 379515 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-2-001 [junit4] 2> 379515 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 379517 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4515424d{/xxv/j,null,AVAILABLE} [junit4] 2> 379517 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@cb565c7{HTTP/1.1,[http/1.1]}{127.0.0.1:60480} [junit4] 2> 379518 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server Started @384923ms [junit4] 2> 379518 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/xxv/j, hostPort=60480, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-2-001/cores} [junit4] 2> 379519 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 379519 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 379519 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 379519 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 379519 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-15T16:24:28.346Z [junit4] 2> 379524 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 379524 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-2-001/solr.xml [junit4] 2> 379542 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60447/solr [junit4] 2> 379560 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 379566 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 379570 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60480_xxv%2Fj [junit4] 2> 379573 INFO (zkCallback-744-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 379574 INFO (zkCallback-740-thread-1-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 379574 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 379575 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 379657 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-2-001/cores [junit4] 2> 379657 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 379661 INFO (OverseerStateUpdate-97622102064824324-127.0.0.1:60455_xxv%2Fj-n_0000000000) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard3 [junit4] 2> 379768 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 379768 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 380689 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 380698 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 380748 WARN (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 380749 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 380776 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 380776 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 380776 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-2-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-2-001/cores/collection1/data/] [junit4] 2> 380777 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50c037d6 [junit4] 2> 380777 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 380782 WARN (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 380834 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 380834 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 380835 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 380835 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 380836 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=45.572265625, floorSegmentMB=0.2255859375, forceMergeDeletesPctAllowed=13.503314255470691, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12684793990289878 [junit4] 2> 380837 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@424b4000[collection1] main] [junit4] 2> 380838 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 380839 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 380839 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 380840 INFO (searcherExecutor-1903-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@424b4000[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 380840 INFO (coreLoadExecutor-1902-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1561953639771144192 [junit4] 2> 380850 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 380850 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 380850 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60480/xxv/j/collection1/ [junit4] 2> 380850 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 380850 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60480/xxv/j/collection1/ has no replicas [junit4] 2> 380850 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 380857 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60480/xxv/j/collection1/ shard3 [junit4] 2> 380970 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 380970 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 381016 INFO (coreZkRegister-1897-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60480_xxv%2Fj c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 381130 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 381130 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 381198 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-3-001/cores/collection1 [junit4] 2> 381201 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-3-001 [junit4] 2> 381201 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 381203 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4929196b{/xxv/j,null,AVAILABLE} [junit4] 2> 381203 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@34f9c2d2{HTTP/1.1,[http/1.1]}{127.0.0.1:60489} [junit4] 2> 381203 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.e.j.s.Server Started @386609ms [junit4] 2> 381204 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/xxv/j, hostPort=60489, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-3-001/cores} [junit4] 2> 381204 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 381205 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 381205 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 381205 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 381205 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-15T16:24:30.032Z [junit4] 2> 381209 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 381209 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-3-001/solr.xml [junit4] 2> 381218 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60447/solr [junit4] 2> 381237 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 381242 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 381246 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60489_xxv%2Fj [junit4] 2> 381248 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 381249 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 381250 INFO (zkCallback-740-thread-3-processing-n:127.0.0.1:60455_xxv%2Fj) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 381250 INFO (zkCallback-762-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 381250 INFO (zkCallback-744-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 381362 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 381362 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 381395 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-3-001/cores [junit4] 2> 381395 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[B3A356A9F3E3B30F]) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 381399 INFO (OverseerStateUpdate-97622102064824324-127.0.0.1:60455_xxv%2Fj-n_0000000000) [n:127.0.0.1:60455_xxv%2Fj ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 381507 INFO (zkCallback-762-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 381507 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 381507 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 382452 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 382462 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 382513 WARN (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 382514 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 382545 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 382545 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 382545 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-3-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_B3A356A9F3E3B30F-001/shard-3-001/cores/collection1/data/] [junit4] 2> 382546 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50c037d6 [junit4] 2> 382547 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 382553 WARN (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 382609 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 382609 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 382610 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 382610 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 382610 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=45.572265625, floorSegmentMB=0.2255859375, forceMergeDeletesPctAllowed=13.503314255470691, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12684793990289878 [junit4] 2> 382610 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c4e9fcd[collection1] main] [junit4] 2> 382612 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 382613 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 382613 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 382614 INFO (searcherExecutor-1914-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5c4e9fcd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 382615 INFO (coreLoadExecutor-1913-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1561953641632366592 [junit4] 2> 382625 INFO (coreZkRegister-1908-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 382625 INFO (coreZkRegister-1908-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 382625 INFO (coreZkRegister-1908-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60489/xxv/j/collection1/ [junit4] 2> 382625 INFO (coreZkRegister-1908-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 382625 INFO (coreZkRegister-1908-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60489/xxv/j/collection1/ has no replicas [junit4] 2> 382625 INFO (coreZkRegister-1908-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 382632 INFO (coreZkRegister-1908-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj x:collection1 c:collection1) [n:127.0.0.1:60489_xxv%2Fj c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60489/xxv/j/collection1/ shard1 [junit4] 2> 382742 INFO (zkCallback-756-thread-1-processing-n:127.0.0.1:60480_xxv%2Fj) [n:127.0.0.1:60480_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 382742 INFO (zkCallback-750-thread-1-processing-n:127.0.0.1:60466_xxv%2Fj) [n:127.0.0.1:60466_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 382742 INFO (zkCallback-762-thread-1-processing-n:127.0.0.1:60489_xxv%2Fj) [n:127.0.0.1:60489_xxv%2Fj ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:Node [...truncated too long message...] at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:91) [junit4] > at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:243) [junit4] > at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:95) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:849) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1955) [junit4] > at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:182) [junit4] > at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:78) [junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173) [junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2464) [junit4] > at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) [junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] > at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395) [junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] > at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] > at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] > at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > at __randomizedtesting.SeedInfo.seed([B3A356A9F3E3B30F]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=4115, name=searcherExecutor-1967-thread-1, state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > 2) Thread[id=3917, name=qtp504926603-3917, state=TIMED_WAITING, group=TGRP-FullSolrCloudDistribCmdsTest] [junit4] > at java.lang.Object.wait(Native Method) [junit4] > at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.waitForEmptyQueue(ConcurrentUpdateSolrClient.java:657) [junit4] > at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:589) [junit4] > at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:91) [junit4] > at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:243) [junit4] > at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:95) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:849) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1955) [junit4] > at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:182) [junit4] > at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:78) [junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173) [junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2464) [junit4] > at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) [junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] > at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395) [junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] > at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] > at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] > at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > at __randomizedtesting.SeedInfo.seed([B3A356A9F3E3B30F]:0) [junit4] Completed [121/699 (1!)] on J1 in 698.81s, 1 test, 3 errors <<< FAILURES! [...truncated 64494 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
