Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/833/
5 tests failed.
REGRESSION: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test
Error Message:
The Monkey ran for over 20 seconds and no jetties were stopped - this is worth
investigating!
Stack Trace:
java.lang.AssertionError: The Monkey ran for over 20 seconds and no jetties
were stopped - this is worth investigating!
at
__randomizedtesting.SeedInfo.seed([9A42D5030E65BEE5:1216EAD9A099D31D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.ChaosMonkey.stopTheMonkey(ChaosMonkey.java:537)
at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test(ChaosMonkeyNothingIsSafeTest.java:189)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
Some resources were not closed, shutdown, or released.
Stack Trace:
java.lang.AssertionError: Some resources were not closed, shutdown, or released.
at __randomizedtesting.SeedInfo.seed([9A42D5030E65BEE5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:234)
at sun.reflect.GeneratedMethodAccessor40.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
7 threads leaked from SUITE scope at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 1) Thread[id=38376,
name=StoppableIndexingThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.lang.Thread.sleep(Native Method) at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:136)
2) Thread[id=38538, name=StoppableSearchThread-EventThread, state=WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native
Method) at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
3) Thread[id=38380, name=StoppableSearchThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.lang.Thread.sleep(Native Method) at
org.apache.solr.cloud.StoppableSearchThread.run(StoppableSearchThread.java:64)
4) Thread[id=38375, name=StoppableIndexingThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.lang.Thread.sleep(Native Method) at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:136)
5) Thread[id=38537, name=StoppableSearchThread-SendThread(127.0.0.1:43441),
state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] at
sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at
sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:200) at
sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:103) at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98) at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349)
at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081) 6)
Thread[id=38374, name=StoppableIndexingThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.lang.Thread.sleep(Native Method) at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:136)
7) Thread[id=38381, name=FullThrottleStopableIndexingThread,
state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.net.PlainSocketImpl.socketConnect(Native Method) at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) at
java.net.Socket.connect(Socket.java:579) at
org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
at
org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
at
org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.request(ConcurrentUpdateSolrClient.java:316)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:754)
at org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:717)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:732)
at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest$FullThrottleStoppableIndexingThread.run(ChaosMonkeyNothingIsSafeTest.java:336)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 7 threads leaked from SUITE
scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest:
1) Thread[id=38376, name=StoppableIndexingThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.lang.Thread.sleep(Native Method)
at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:136)
2) Thread[id=38538, name=StoppableSearchThread-EventThread, state=WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
3) Thread[id=38380, name=StoppableSearchThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.lang.Thread.sleep(Native Method)
at
org.apache.solr.cloud.StoppableSearchThread.run(StoppableSearchThread.java:64)
4) Thread[id=38375, name=StoppableIndexingThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.lang.Thread.sleep(Native Method)
at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:136)
5) Thread[id=38537, name=StoppableSearchThread-SendThread(127.0.0.1:43441),
state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method)
at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:200)
at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:103)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
6) Thread[id=38374, name=StoppableIndexingThread, state=TIMED_WAITING,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.lang.Thread.sleep(Native Method)
at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:136)
7) Thread[id=38381, name=FullThrottleStopableIndexingThread, state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.net.PlainSocketImpl.socketConnect(Native Method)
at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:579)
at
org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
at
org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
at
org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.request(ConcurrentUpdateSolrClient.java:316)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:754)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:717)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:732)
at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest$FullThrottleStoppableIndexingThread.run(ChaosMonkeyNothingIsSafeTest.java:336)
at __randomizedtesting.SeedInfo.seed([9A42D5030E65BEE5]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=38374, name=StoppableIndexingThread, state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.io.StringWriter.write(StringWriter.java:77) at
org.apache.solr.common.util.XML.escape(XML.java:204) at
org.apache.solr.common.util.XML.escapeCharData(XML.java:77) at
org.apache.solr.common.util.XML.writeXML(XML.java:147) at
org.apache.solr.client.solrj.util.ClientUtils.writeVal(ClientUtils.java:163)
at
org.apache.solr.client.solrj.util.ClientUtils.writeXML(ClientUtils.java:131)
at
org.apache.solr.client.solrj.request.UpdateRequest.writeXML(UpdateRequest.java:379)
at
org.apache.solr.client.solrj.request.UpdateRequest.getXML(UpdateRequest.java:295)
at
org.apache.solr.client.solrj.request.RequestWriter.getContentStream(RequestWriter.java:67)
at
org.apache.solr.client.solrj.request.RequestWriter$LazyContentStream.getDelegate(RequestWriter.java:95)
at
org.apache.solr.client.solrj.request.RequestWriter$LazyContentStream.getName(RequestWriter.java:105)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.createMethod(HttpSolrClient.java:342)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:152)
at
org.apache.solr.cloud.StoppableIndexingThread.indexDocs(StoppableIndexingThread.java:177)
at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:116)
2) Thread[id=38381, name=FullThrottleStopableIndexingThread,
state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.net.PlainSocketImpl.socketConnect(Native Method) at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) at
java.net.Socket.connect(Socket.java:579) at
org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
at
org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
at
org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.request(ConcurrentUpdateSolrClient.java:316)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:754)
at org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:717)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:732)
at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest$FullThrottleStoppableIndexingThread.run(ChaosMonkeyNothingIsSafeTest.java:336)
3) Thread[id=38375, name=StoppableIndexingThread, state=BLOCKED,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.io.PrintStream.println(PrintStream.java:806) at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:121)
4) Thread[id=38537, name=StoppableSearchThread-SendThread(127.0.0.1:43441),
state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] at
sun.nio.ch.KQueueArrayWrapper.interrupt(Native Method) at
sun.nio.ch.KQueueArrayWrapper.interrupt(KQueueArrayWrapper.java:205) at
sun.nio.ch.KQueueSelectorImpl.wakeup(KQueueSelectorImpl.java:243) at
java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:210)
at
java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:216)
at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:102)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98) at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349)
at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081) 5)
Thread[id=38380, name=StoppableSearchThread, state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
sun.reflect.Reflection.getCallerClass(Native Method) at
java.lang.Class.getDeclaredFields(Class.java:1805) at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory.getBoundFields(ReflectiveTypeAdapterFactory.java:103)
at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory.create(ReflectiveTypeAdapterFactory.java:65)
at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.MiniGson.getAdapter(MiniGson.java:92)
at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.Gson.toJson(Gson.java:504)
at
com.carrotsearch.ant.tasks.junit4.events.Serializer.serialize(Serializer.java:87)
at
com.carrotsearch.ant.tasks.junit4.slave.SlaveMain$4.write(SlaveMain.java:410)
at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
at java.io.PrintStream.flush(PrintStream.java:338) at
java.io.FilterOutputStream.flush(FilterOutputStream.java:140) at
java.io.PrintStream.write(PrintStream.java:482) at
sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221) at
sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291) at
sun.nio.cs.StreamEncoder.flushBuffer(StreamEncoder.java:104) at
java.io.OutputStreamWriter.flushBuffer(OutputStreamWriter.java:185) at
java.io.PrintStream.write(PrintStream.java:527) at
java.io.PrintStream.print(PrintStream.java:669) at
java.io.PrintStream.println(PrintStream.java:823) at
java.lang.Throwable$WrappedPrintStream.println(Throwable.java:748) at
java.lang.Throwable.printStackTrace(Throwable.java:658) at
java.lang.Throwable.printStackTrace(Throwable.java:643) at
java.lang.Throwable.printStackTrace(Throwable.java:634) at
org.apache.solr.cloud.StoppableSearchThread.run(StoppableSearchThread.java:59)
6) Thread[id=38376, name=StoppableIndexingThread, state=BLOCKED,
group=TGRP-ChaosMonkeyNothingIsSafeTest] at
java.io.PrintStream.println(PrintStream.java:806) at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:121)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=38374, name=StoppableIndexingThread, state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.io.StringWriter.write(StringWriter.java:77)
at org.apache.solr.common.util.XML.escape(XML.java:204)
at org.apache.solr.common.util.XML.escapeCharData(XML.java:77)
at org.apache.solr.common.util.XML.writeXML(XML.java:147)
at
org.apache.solr.client.solrj.util.ClientUtils.writeVal(ClientUtils.java:163)
at
org.apache.solr.client.solrj.util.ClientUtils.writeXML(ClientUtils.java:131)
at
org.apache.solr.client.solrj.request.UpdateRequest.writeXML(UpdateRequest.java:379)
at
org.apache.solr.client.solrj.request.UpdateRequest.getXML(UpdateRequest.java:295)
at
org.apache.solr.client.solrj.request.RequestWriter.getContentStream(RequestWriter.java:67)
at
org.apache.solr.client.solrj.request.RequestWriter$LazyContentStream.getDelegate(RequestWriter.java:95)
at
org.apache.solr.client.solrj.request.RequestWriter$LazyContentStream.getName(RequestWriter.java:105)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.createMethod(HttpSolrClient.java:342)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:152)
at
org.apache.solr.cloud.StoppableIndexingThread.indexDocs(StoppableIndexingThread.java:177)
at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:116)
2) Thread[id=38381, name=FullThrottleStopableIndexingThread, state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.net.PlainSocketImpl.socketConnect(Native Method)
at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:579)
at
org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
at
org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
at
org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.request(ConcurrentUpdateSolrClient.java:316)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:754)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:717)
at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:732)
at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest$FullThrottleStoppableIndexingThread.run(ChaosMonkeyNothingIsSafeTest.java:336)
3) Thread[id=38375, name=StoppableIndexingThread, state=BLOCKED,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.io.PrintStream.println(PrintStream.java:806)
at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:121)
4) Thread[id=38537, name=StoppableSearchThread-SendThread(127.0.0.1:43441),
state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at sun.nio.ch.KQueueArrayWrapper.interrupt(Native Method)
at sun.nio.ch.KQueueArrayWrapper.interrupt(KQueueArrayWrapper.java:205)
at sun.nio.ch.KQueueSelectorImpl.wakeup(KQueueSelectorImpl.java:243)
at
java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:210)
at
java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:216)
at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:102)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
5) Thread[id=38380, name=StoppableSearchThread, state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at sun.reflect.Reflection.getCallerClass(Native Method)
at java.lang.Class.getDeclaredFields(Class.java:1805)
at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory.getBoundFields(ReflectiveTypeAdapterFactory.java:103)
at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory.create(ReflectiveTypeAdapterFactory.java:65)
at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.MiniGson.getAdapter(MiniGson.java:92)
at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.Gson.toJson(Gson.java:504)
at
com.carrotsearch.ant.tasks.junit4.events.Serializer.serialize(Serializer.java:87)
at
com.carrotsearch.ant.tasks.junit4.slave.SlaveMain$4.write(SlaveMain.java:410)
at
java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
at java.io.PrintStream.flush(PrintStream.java:338)
at java.io.FilterOutputStream.flush(FilterOutputStream.java:140)
at java.io.PrintStream.write(PrintStream.java:482)
at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221)
at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291)
at sun.nio.cs.StreamEncoder.flushBuffer(StreamEncoder.java:104)
at java.io.OutputStreamWriter.flushBuffer(OutputStreamWriter.java:185)
at java.io.PrintStream.write(PrintStream.java:527)
at java.io.PrintStream.print(PrintStream.java:669)
at java.io.PrintStream.println(PrintStream.java:823)
at java.lang.Throwable$WrappedPrintStream.println(Throwable.java:748)
at java.lang.Throwable.printStackTrace(Throwable.java:658)
at java.lang.Throwable.printStackTrace(Throwable.java:643)
at java.lang.Throwable.printStackTrace(Throwable.java:634)
at
org.apache.solr.cloud.StoppableSearchThread.run(StoppableSearchThread.java:59)
6) Thread[id=38376, name=StoppableIndexingThread, state=BLOCKED,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.io.PrintStream.println(PrintStream.java:806)
at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:121)
at __randomizedtesting.SeedInfo.seed([9A42D5030E65BEE5]:0)
REGRESSION: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=3553,
name=parallelCoreAdminExecutor-1150-thread-21, state=RUNNABLE,
group=TGRP-MultiThreadedOCPTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=3553,
name=parallelCoreAdminExecutor-1150-thread-21, state=RUNNABLE,
group=TGRP-MultiThreadedOCPTest]
at
__randomizedtesting.SeedInfo.seed([9A42D5030E65BEE5:1216EAD9A099D31D]:0)
Caused by: java.lang.AssertionError: Too many closes on SolrCore
at __randomizedtesting.SeedInfo.seed([9A42D5030E65BEE5]:0)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1138)
at org.apache.solr.common.util.IOUtils.closeQuietly(IOUtils.java:31)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:535)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:494)
at
org.apache.solr.handler.admin.CoreAdminHandler.handleCreateAction(CoreAdminHandler.java:598)
at
org.apache.solr.handler.admin.CoreAdminHandler.handleRequestInternal(CoreAdminHandler.java:212)
at
org.apache.solr.handler.admin.CoreAdminHandler$ParallelCoreAdminHandlerThread.run(CoreAdminHandler.java:1219)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:148)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10774 lines...]
[junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest
[junit4] 2> Creating dataDir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/init-core-data-001
[junit4] 2> 741219 T3206 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 741220 T3206 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /ls_f/p
[junit4] 2> 741227 T3206 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 741228 T3207 oasc.ZkTestServer$2$1.setClientPort client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 741228 T3207 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 741328 T3206 oasc.ZkTestServer.run start zk server on
port:41215
[junit4] 2> 741329 T3206
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 741330 T3206 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 741334 T3214 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@55b2e3ce
name:ZooKeeperConnection Watcher:127.0.0.1:41215 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 741334 T3206 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 741335 T3206 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 741335 T3206 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 741338 T3206
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 741340 T3206 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 741342 T3217 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5c29526e
name:ZooKeeperConnection Watcher:127.0.0.1:41215/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 741342 T3206 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 741343 T3206 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 741343 T3206 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 741346 T3206 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 741348 T3206 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 741349 T3206 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 741351 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 741352 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 741355 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 741355 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 741458 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 741458 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 741460 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 741461 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 741463 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 741464 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 741465 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 741466 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 741467 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 741468 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 741469 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 741469 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 741471 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 741471 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 741481 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 741482 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 741484 T3206 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 741485 T3206 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 742550 T3206 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1
[junit4] 2> 742553 T3206 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 742555 T3206 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@445a8dd8{/ls_f/p,null,AVAILABLE}
[junit4] 2> 742556 T3206 oejs.AbstractConnector.doStart Started
ServerConnector@611d5c3e{HTTP/1.1}{127.0.0.1:41218}
[junit4] 2> 742556 T3206 oejs.Server.doStart Started @747373ms
[junit4] 2> 742556 T3206 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores, hostPort=41218, hostContext=/ls_f/p}
[junit4] 2> 742557 T3206 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@231bf785
[junit4] 2> 742557 T3206 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/'
[junit4] 2> 742589 T3206 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/solr.xml
[junit4] 2> 742628 T3206 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores
[junit4] 2> 742629 T3206 oasc.CoreContainer.<init> New CoreContainer
2130852050
[junit4] 2> 742629 T3206 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/]
[junit4] 2> 742629 T3206 oasc.CoreContainer.load loading shared library:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/lib
[junit4] 2> 742630 T3206 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/lib).
[junit4] 2> 742641 T3206 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 742646 T3206 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 742647 T3206 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 742647 T3206 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 742647 T3206 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 742648 T3206 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41215/solr
[junit4] 2> 742648 T3206 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 742648 T3206
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 742649 T3206 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 742651 T3231 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6039a1fa
name:ZooKeeperConnection Watcher:127.0.0.1:41215 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 742652 T3206 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 742653 T3206 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 742655 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 742658 T3234 n:127.0.0.1:41218_ls_f%2Fp
oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7631067 name:ZooKeeperConnection
Watcher:127.0.0.1:41215/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 742658 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 742660 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 742663 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 742665 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 742667 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 742669 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 742671 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 742673 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 742675 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 742676 T3206 n:127.0.0.1:41218_ls_f%2Fp
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41218_ls_f%2Fp
[junit4] 2> 742677 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41218_ls_f%2Fp
[junit4] 2> 742679 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 742680 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 742682 T3206 n:127.0.0.1:41218_ls_f%2Fp oasc.Overseer.close
Overseer (id=null) closing
[junit4] 2> 742684 T3206 n:127.0.0.1:41218_ls_f%2Fp
oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader
127.0.0.1:41218_ls_f%2Fp
[junit4] 2> 742684 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 742685 T3206 n:127.0.0.1:41218_ls_f%2Fp oasc.Overseer.start
Overseer (id=93743230558928899-127.0.0.1:41218_ls_f%2Fp-n_0000000000) starting
[junit4] 2> 742687 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 742693 T3206 n:127.0.0.1:41218_ls_f%2Fp
oasc.OverseerAutoReplicaFailoverThread.<init> Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 742694 T3236 n:127.0.0.1:41218_ls_f%2Fp
oasc.OverseerCollectionProcessor.run Process current queue of collection
creations
[junit4] 2> 742694 T3206 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 742695 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 742699 T3206 n:127.0.0.1:41218_ls_f%2Fp
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores
[junit4] 2> 742703 T3206 n:127.0.0.1:41218_ls_f%2Fp
oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {shard=, transient=false,
dataDir=data/, collection=control_collection,
instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1, loadOnStartup=true,
absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/, name=collection1,
schema=schema.xml, coreNodeName=, config=solrconfig.xml}
[junit4] 2> 742703 T3206 n:127.0.0.1:41218_ls_f%2Fp
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/
[junit4] 2> 742704 T3206 n:127.0.0.1:41218_ls_f%2Fp
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 742705 T3238 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
x:collection1 oasc.ZkController.publish publishing core=collection1 state=down
collection=control_collection
[junit4] 2> 742705 T3238 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
x:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 742706 T3238 n:127.0.0.1:41218_ls_f%2Fp
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 742706 T3234 n:127.0.0.1:41218_ls_f%2Fp
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 742707 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "numShards":"1",
[junit4] 2> "node_name":"127.0.0.1:41218_ls_f%2Fp",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1:41218/ls_f/p";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "collection":"control_collection"} current state
version: 0
[junit4] 2> 742708 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "numShards":"1",
[junit4] 2> "node_name":"127.0.0.1:41218_ls_f%2Fp",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1:41218/ls_f/p";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "collection":"control_collection"}
[junit4] 2> 742708 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasco.ClusterStateMutator.createCollection building a new cName:
control_collection
[junit4] 2> 742709 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 742710 T3234 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 1)
[junit4] 2> 743707 T3238 n:127.0.0.1:41218_ls_f%2Fp
oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for
collection1
[junit4] 2> 743708 T3238 n:127.0.0.1:41218_ls_f%2Fp
oasc.ZkController.createCollectionZkNode Check for collection
zkNode:control_collection
[junit4] 2> 743709 T3238 n:127.0.0.1:41218_ls_f%2Fp
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 743709 T3238 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/control_collection
[junit4] 2> 743709 T3238 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader.readConfigName path=/collections/control_collection
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 743710 T3238 n:127.0.0.1:41218_ls_f%2Fp
oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/'
[junit4] 2> 743730 T3238 n:127.0.0.1:41218_ls_f%2Fp oasc.Config.<init>
loaded config solrconfig.xml with version 0
[junit4] 2> 743741 T3238 n:127.0.0.1:41218_ls_f%2Fp
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 743764 T3238 n:127.0.0.1:41218_ls_f%2Fp oasc.SolrConfig.<init>
Using Lucene MatchVersion: 5.2.0
[junit4] 2> 743815 T3238 n:127.0.0.1:41218_ls_f%2Fp oasc.SolrConfig.<init>
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 743916 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 743934 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 744359 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider.init Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 744376 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 744380 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 744391 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.FileExchangeRateProvider.reload Reloading exchange rates from file
currency.xml
[junit4] 2> 744396 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.FileExchangeRateProvider.reload Reloading exchange rates from file
currency.xml
[junit4] 2> 744401 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 744402 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 744402 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 744402 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 744403 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 744403 T3238 n:127.0.0.1:41218_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 744404 T3238 n:127.0.0.1:41218_ls_f%2Fp
oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration
from collection control_collection
[junit4] 2> 744404 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 744405 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at
[/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/], dataDir=[null]
[junit4] 2> 744405 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to
JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a09bc5
[junit4] 2> 744407 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.CachingDirectoryFactory.get return new directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/data
[junit4] 2> 744408 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrCore.getNewIndexDir New index directory detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/data/index/
[junit4] 2> 744409 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrCore.initIndex WARN [collection1] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/data/index' doesn't exist.
Creating new index...
[junit4] 2> 744410 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.CachingDirectoryFactory.get return new directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/control-001/cores/collection1/data/index
[junit4] 2> 744411 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@a6b1cba
[junit4] 2> 744416 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=RawDirectoryWrapper(NIOFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/index-NIOFSDirectory-001
lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e27a0b3),segFN=segments_1,generation=1}
[junit4] 2> 744417 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 744423 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 744424 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 744424 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 744425 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 744425 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 744425 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 744426 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 744426 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 744427 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined
as default, creating implicit default
[junit4] 2> 744446 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 744449 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 744451 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 744454 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 744460 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.RequestHandlers.initHandlersFromConfig Registered paths:
/admin/ping,/admin/file,/admin/properties,/admin/system,standard,/admin/threads,/replication,/config,/admin/segments,/get,/schema,/update/json/docs,/admin/luke,/update/json,/update,/admin/plugins,/admin/mbeans,/update/csv,/admin/logging
[junit4] 2> 744462 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrCore.initStatsCache Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 744464 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasu.UpdateHandler.<init> Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 744464 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 744467 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 744467 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 744468 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=84.0185546875,
floorSegmentMB=0.4873046875, forceMergeDeletesPctAllowed=28.494501373303322,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7652803849019228
[junit4] 2> 744470 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=RawDirectoryWrapper(NIOFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/index-NIOFSDirectory-001
lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e27a0b3),segFN=segments_1,generation=1}
[junit4] 2> 744471 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 744471 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oass.SolrIndexSearcher.<init> Opening Searcher@365a0282[collection1] main
[junit4] 2> 744472 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/control_collection
[junit4] 2> 744473 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oascc.ZkStateReader.readConfigName path=/collections/control_collection
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 744473 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for
the RestManager with znodeBase: /configs/conf1
[junit4] 2> 744473 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 744474 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 744474 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasr.ManagedResourceStorage.load Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 744475 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 744475 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 744476 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 744476 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 744477 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 744477 T3239 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.SolrCore.registerSearcher [collection1] Registered new searcher
Searcher@365a0282[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 744477 T3238 n:127.0.0.1:41218_ls_f%2Fp x:collection1
oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 744478 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:41218/ls_f/p
collection:control_collection shard:shard1
[junit4] 2> 744479 T3206 n:127.0.0.1:41218_ls_f%2Fp
oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
[junit4] 2> 744479 T3206 n:127.0.0.1:41218_ls_f%2Fp
oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 744479 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 744482 T3206
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 744483 T3206 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 744484 T3245 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@77fdefcd
name:ZooKeeperConnection Watcher:127.0.0.1:41215/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 744484 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running
the leader process for shard shard1
[junit4] 2> 744484 T3206 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 744485 T3206 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 744485 T3206
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 744486 T3234 n:127.0.0.1:41218_ls_f%2Fp
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 744486 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp
Enough replicas found to continue.
[junit4] 2> 744487 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may
be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C219 name=collection1
org.apache.solr.core.SolrCore@71882120
url=http://127.0.0.1:41218/ls_f/p/collection1 node=127.0.0.1:41218_ls_f%2Fp
C219_STATE=coll:control_collection core:collection1
props:{node_name=127.0.0.1:41218_ls_f%2Fp,
base_url=http://127.0.0.1:41218/ls_f/p, core=collection1, state=down}
[junit4] 2> 744487 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 C219 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:41218/ls_f/p/collection1/
[junit4] 2> 744487 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 744487 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 C219 oasc.SyncStrategy.syncReplicas Sync Success - now
sync replicas to me
[junit4] 2> 744488 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 C219 oasc.SyncStrategy.syncToMe
http://127.0.0.1:41218/ls_f/p/collection1/ has no replicas
[junit4] 2> 744488 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am
the new leader: http://127.0.0.1:41218/ls_f/p/collection1/ shard1
[junit4] 2> 744488 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 744489 T3206 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 744489 T3234 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 1)
[junit4] 2> 744489 T3245 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 744492 T3234 n:127.0.0.1:41218_ls_f%2Fp
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 744493 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:41218/ls_f/p";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 744596 T3245 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 744596 T3234 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 1)
[junit4] 2> 744645 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ZkController.register We are
http://127.0.0.1:41218/ls_f/p/collection1/ and leader is
http://127.0.0.1:41218/ls_f/p/collection1/
[junit4] 2> 744646 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for
core=collection1 baseURL=http://127.0.0.1:41218/ls_f/p
[junit4] 2> 744646 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no
recovery necessary
[junit4] 2> 744646 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1
state=active collection=control_collection
[junit4] 2> 744647 T3242 n:127.0.0.1:41218_ls_f%2Fp c:control_collection
s:shard1 x:collection1 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 744648 T3234 n:127.0.0.1:41218_ls_f%2Fp
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 744649 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:41218_ls_f%2Fp",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "base_url":"http://127.0.0.1:41218/ls_f/p";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active",
[junit4] 2> "collection":"control_collection"} current state
version: 3
[junit4] 2> 744650 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:41218_ls_f%2Fp",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "base_url":"http://127.0.0.1:41218/ls_f/p";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active",
[junit4] 2> "collection":"control_collection"}
[junit4] 2> 744753 T3245 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 744753 T3234 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 1)
[junit4] 2> 746023 T3206 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores/collection1
[junit4] 2> 746026 T3206 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1 in directory
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001
[junit4] 2> 746028 T3206 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 746030 T3206 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@266fd79f{/ls_f/p,null,AVAILABLE}
[junit4] 2> 746031 T3206 oejs.AbstractConnector.doStart Started
ServerConnector@1960a34c{HTTP/1.1}{127.0.0.1:41222}
[junit4] 2> 746032 T3206 oejs.Server.doStart Started @750849ms
[junit4] 2> 746032 T3206 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores, hostPort=41222, hostContext=/ls_f/p,
solrconfig=solrconfig.xml}
[junit4] 2> 746033 T3206 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@231bf785
[junit4] 2> 746033 T3206 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/'
[junit4] 2> 746080 T3206 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/solr.xml
[junit4] 2> 746135 T3206 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores
[junit4] 2> 746136 T3206 oasc.CoreContainer.<init> New CoreContainer
721023921
[junit4] 2> 746137 T3206 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/]
[junit4] 2> 746137 T3206 oasc.CoreContainer.load loading shared library:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/lib
[junit4] 2> 746138 T3206 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/lib).
[junit4] 2> 746152 T3206 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 746160 T3206 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 746161 T3206 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 746162 T3206 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 746162 T3206 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 746162 T3206 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41215/solr
[junit4] 2> 746163 T3206 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 746163 T3206
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 746165 T3206 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 746167 T3259 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@31e9178f
name:ZooKeeperConnection Watcher:127.0.0.1:41215 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 746167 T3206 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 746168 T3206 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 746170 T3206 n:127.0.0.1:41222_ls_f%2Fp
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 746173 T3262 n:127.0.0.1:41222_ls_f%2Fp
oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@58474b3b
name:ZooKeeperConnection Watcher:127.0.0.1:41215/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 746173 T3206 n:127.0.0.1:41222_ls_f%2Fp
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 746178 T3206 n:127.0.0.1:41222_ls_f%2Fp
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 747183 T3206 n:127.0.0.1:41222_ls_f%2Fp
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41222_ls_f%2Fp
[junit4] 2> 747184 T3206 n:127.0.0.1:41222_ls_f%2Fp
oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41222_ls_f%2Fp
[junit4] 2> 747186 T3206 n:127.0.0.1:41222_ls_f%2Fp oasc.Overseer.close
Overseer (id=null) closing
[junit4] 2> 747189 T3206 n:127.0.0.1:41222_ls_f%2Fp
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores
[junit4] 2> 747192 T3206 n:127.0.0.1:41222_ls_f%2Fp
oasc.CoreDescriptor.<init> CORE DESCRIPTOR:
{instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores/collection1, dataDir=data/,
loadOnStartup=true, shard=, coreNodeName=, config=solrconfig.xml,
transient=false,
absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores/collection1/, schema=schema.xml,
collection=collection1, name=collection1}
[junit4] 2> 747193 T3206 n:127.0.0.1:41222_ls_f%2Fp
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores/collection1/
[junit4] 2> 747193 T3206 n:127.0.0.1:41222_ls_f%2Fp
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 747194 T3263 n:127.0.0.1:41222_ls_f%2Fp c:collection1
x:collection1 oasc.ZkController.publish publishing core=collection1 state=down
collection=collection1
[junit4] 2> 747195 T3263 n:127.0.0.1:41222_ls_f%2Fp c:collection1
x:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 747196 T3263 n:127.0.0.1:41222_ls_f%2Fp
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 747196 T3234 n:127.0.0.1:41218_ls_f%2Fp
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 747197 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:41222_ls_f%2Fp",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1:41222/ls_f/p";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 747197 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:41222_ls_f%2Fp",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1:41222/ls_f/p";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "collection":"collection1"}
[junit4] 2> 747198 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 747198 T3235 n:127.0.0.1:41218_ls_f%2Fp
oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 747199 T3245 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 747199 T3262 n:127.0.0.1:41222_ls_f%2Fp
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 2)
[junit4] 2> 747199 T3234 n:127.0.0.1:41218_ls_f%2Fp
oascc.ZkStateReader$2.process A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 2)
[junit4] 2> 748196 T3263 n:127.0.0.1:41222_ls_f%2Fp
oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for
collection1
[junit4] 2> 748197 T3263 n:127.0.0.1:41222_ls_f%2Fp
oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 748198 T3263 n:127.0.0.1:41222_ls_f%2Fp
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 748198 T3263 n:127.0.0.1:41222_ls_f%2Fp
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/collection1
[junit4] 2> 748198 T3263 n:127.0.0.1:41222_ls_f%2Fp
oascc.ZkStateReader.readConfigName path=/collections/collection1
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 748199 T3263 n:127.0.0.1:41222_ls_f%2Fp
oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.MultiThreadedOCPTest
9A42D5030E65BEE5-001/shard-1-001/cores/collection1/'
[junit4] 2> 748216 T3263 n:127.0.0.1:41222_ls_f%2Fp oasc.Config.<init>
loaded config solrconfig.xml with version 0
[junit4] 2> 748226 T3263 n:127.0.0.1:41222_ls_f%2Fp
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 748242 T3263 n:127.0.0.1:41222_ls_f%2Fp oasc.SolrConfig.<init>
Using Lucene MatchVersion: 5.2.0
[junit4] 2> 748276 T3263 n:127.0.0.1:41222_ls_f%2Fp oasc.SolrConfig.<init>
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 748378 T3263 n:127.0.0.1:41222_ls_f%2Fp
oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 748403 T3263 n:127.0.0.1:41222_ls_f%2Fp
oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 748680 T3263 n:127.0.0.1:41222_ls_f%2Fp
oass.OpenExchangeRatesOrgProvider.init Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 748693 T3263 n:127.0.0.1:41222_ls_f%2Fp
oass.IndexSchema.readSchema default search field in schema is text
[...truncated too long message...]
etXML(UpdateRequest.java:295)
[junit4] > at
org.apache.solr.client.solrj.request.RequestWriter.getContentStream(RequestWriter.java:67)
[junit4] > at
org.apache.solr.client.solrj.request.RequestWriter$LazyContentStream.getDelegate(RequestWriter.java:95)
[junit4] > at
org.apache.solr.client.solrj.request.RequestWriter$LazyContentStream.getName(RequestWriter.java:105)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.createMethod(HttpSolrClient.java:342)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
[junit4] > at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
[junit4] > at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:152)
[junit4] > at
org.apache.solr.cloud.StoppableIndexingThread.indexDocs(StoppableIndexingThread.java:177)
[junit4] > at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:116)
[junit4] > 2) Thread[id=38381,
name=FullThrottleStopableIndexingThread, state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at java.net.PlainSocketImpl.socketConnect(Native
Method)
[junit4] > at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
[junit4] > at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
[junit4] > at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
[junit4] > at
java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] > at java.net.Socket.connect(Socket.java:579)
[junit4] > at
org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] > at
org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
[junit4] > at
org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] > at
org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
[junit4] > at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
[junit4] > at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
[junit4] > at
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.request(ConcurrentUpdateSolrClient.java:316)
[junit4] > at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:754)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:717)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.deleteById(SolrClient.java:732)
[junit4] > at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest$FullThrottleStoppableIndexingThread.run(ChaosMonkeyNothingIsSafeTest.java:336)
[junit4] > 3) Thread[id=38375, name=StoppableIndexingThread,
state=BLOCKED, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at java.io.PrintStream.println(PrintStream.java:806)
[junit4] > at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:121)
[junit4] > 4) Thread[id=38537,
name=StoppableSearchThread-SendThread(127.0.0.1:43441), state=RUNNABLE,
group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at sun.nio.ch.KQueueArrayWrapper.interrupt(Native
Method)
[junit4] > at
sun.nio.ch.KQueueArrayWrapper.interrupt(KQueueArrayWrapper.java:205)
[junit4] > at
sun.nio.ch.KQueueSelectorImpl.wakeup(KQueueSelectorImpl.java:243)
[junit4] > at
java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:210)
[junit4] > at
java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:216)
[junit4] > at
sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:102)
[junit4] > at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
[junit4] > at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] > 5) Thread[id=38380, name=StoppableSearchThread,
state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at java.lang.Class.getDeclaredFields(Class.java:1805)
[junit4] > at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory.getBoundFields(ReflectiveTypeAdapterFactory.java:103)
[junit4] > at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory.create(ReflectiveTypeAdapterFactory.java:65)
[junit4] > at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.MiniGson.getAdapter(MiniGson.java:92)
[junit4] > at
com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.Gson.toJson(Gson.java:504)
[junit4] > at
com.carrotsearch.ant.tasks.junit4.events.Serializer.serialize(Serializer.java:87)
[junit4] > at
java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
[junit4] > at
java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
[junit4] > at java.io.PrintStream.flush(PrintStream.java:338)
[junit4] > at
java.io.FilterOutputStream.flush(FilterOutputStream.java:140)
[junit4] > at java.io.PrintStream.write(PrintStream.java:482)
[junit4] > at
sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221)
[junit4] > at
sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291)
[junit4] > at
sun.nio.cs.StreamEncoder.flushBuffer(StreamEncoder.java:104)
[junit4] > at
java.io.OutputStreamWriter.flushBuffer(OutputStreamWriter.java:185)
[junit4] > at java.io.PrintStream.write(PrintStream.java:527)
[junit4] > at java.io.PrintStream.print(PrintStream.java:669)
[junit4] > at java.io.PrintStream.println(PrintStream.java:823)
[junit4] > at
java.lang.Throwable$WrappedPrintStream.println(Throwable.java:748)
[junit4] > at
java.lang.Throwable.printStackTrace(Throwable.java:658)
[junit4] > at
java.lang.Throwable.printStackTrace(Throwable.java:643)
[junit4] > at
java.lang.Throwable.printStackTrace(Throwable.java:634)
[junit4] > at
org.apache.solr.cloud.StoppableSearchThread.run(StoppableSearchThread.java:59)
[junit4] > 6) Thread[id=38376, name=StoppableIndexingThread,
state=BLOCKED, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at java.io.PrintStream.println(PrintStream.java:806)
[junit4] > at
org.apache.solr.cloud.StoppableIndexingThread.run(StoppableIndexingThread.java:121)
[junit4] > at
__randomizedtesting.SeedInfo.seed([9A42D5030E65BEE5]:0)
[junit4] Completed [228/485] on J2 in 154.31s, 1 test, 2 failures, 2 errors
<<< FAILURES!
[...truncated 799 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:543:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:484:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:229:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:511:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1433:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:991:
There were test failures: 485 suites, 1949 tests, 3 suite-level errors, 1
error, 1 failure, 972 ignored (9 assumptions)
Total time: 139 minutes 49 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #832
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 698197025 bytes
Compression is 0.0%
Took 2 min 10 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
