You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/04/12 10:18:03 UTC
[JENKINS] Lucene-Solr-4.7-Linux (32bit/ibm-j9-jdk6) - Build # 98 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.7-Linux/98/
Java: 32bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
7 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([D050F4563AC18616]:0)
REGRESSION: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSearch
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([D050F4563AC18616]:0)
REGRESSION: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testDistribSearch
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:53190/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:53190/collection1
at __randomizedtesting.SeedInfo.seed([D050F4563AC18616:51B67A4E4D9EE62A]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:503)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:199)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:118)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.index_specific(AbstractFullDistribZkTestBase.java:697)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingWithSuss(FullSolrCloudDistribCmdsTest.java:443)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.doTest(FullSolrCloudDistribCmdsTest.java:134)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:870)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:761)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:140)
at com.ibm.jsse2.b.a(b.java:20)
at com.ibm.jsse2.b.a(b.java:44)
at com.ibm.jsse2.SSLSocketImpl.a(SSLSocketImpl.java:601)
at com.ibm.jsse2.SSLSocketImpl.a(SSLSocketImpl.java:798)
at com.ibm.jsse2.f.read(f.java:32)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:395)
... 48 more
REGRESSION: org.apache.solr.handler.component.DistributedQueryElevationComponentTest.testDistribSearch
Error Message:
.responseHeader.params.rows!=version (unordered or missing)
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.params.rows!=version (unordered or missing)
at __randomizedtesting.SeedInfo.seed([D050F4563AC18616:51B67A4E4D9EE62A]:0)
at junit.framework.Assert.fail(Assert.java:50)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:843)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:862)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:565)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:545)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:524)
at org.apache.solr.handler.component.DistributedQueryElevationComponentTest.doTest(DistributedQueryElevationComponentTest.java:81)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:870)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:761)
REGRESSION: org.apache.solr.handler.component.TermVectorComponentDistributedTest.testDistribSearch
Error Message:
.termVectors.0.test_basictv!=test_offtv (unordered or missing)
Stack Trace:
junit.framework.AssertionFailedError: .termVectors.0.test_basictv!=test_offtv (unordered or missing)
at __randomizedtesting.SeedInfo.seed([D050F4563AC18616:51B67A4E4D9EE62A]:0)
at junit.framework.Assert.fail(Assert.java:50)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:843)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:862)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:565)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:545)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:524)
at org.apache.solr.handler.component.TermVectorComponentDistributedTest.doTest(TermVectorComponentDistributedTest.java:137)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:876)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:761)
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection
Error Message:
expected:<1> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<3>
at __randomizedtesting.SeedInfo.seed([D050F4563AC18616:8B600F9E3986256A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:761)
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testMultiThreaded
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([D050F4563AC18616:B1C52EB6D2108CE0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testMultiThreaded(SignatureUpdateProcessorFactoryTest.java:222)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:761)
Build Log:
[...truncated 9803 lines...]
[junit4] Suite: org.apache.solr.handler.component.TermVectorComponentDistributedTest
[junit4] 2> 33882 T89 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 33883 T89 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /tm_gr/h
[junit4] 2> 33887 T89 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 33890 T89 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 33895 T89 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 33900 T89 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:54941
[junit4] 2> 33906 T89 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 33907 T89 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 33908 T89 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 33909 T89 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 33939 T89 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 33989 T89 oasc.CoreContainer.<init> New CoreContainer 561455479
[junit4] 2> 33990 T89 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 33991 T89 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 33992 T89 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https
[junit4] 2> 33993 T89 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 33994 T89 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 33995 T89 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 33996 T89 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 33997 T89 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 33998 T89 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 33999 T89 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 34013 T89 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 34014 T89 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 34015 T89 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 34021 T99 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 34022 T99 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 34023 T99 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 34024 T99 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 34087 T99 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 34169 T99 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 34171 T99 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 34181 T99 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 34801 T99 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 34802 T99 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 34803 T99 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 34809 T99 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 34812 T99 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 34828 T99 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 34835 T99 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 34840 T99 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 34841 T99 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 34842 T99 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 34842 T99 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 34843 T99 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 34844 T99 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 34845 T99 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 34845 T99 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data/
[junit4] 2> 34846 T99 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3f303f30
[junit4] 2> 34848 T99 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data
[junit4] 2> 34848 T99 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data/index/
[junit4] 2> 34849 T99 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 34850 T99 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data/index
[junit4] 2> 34850 T99 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=77.0068359375, floorSegmentMB=0.3115234375, forceMergeDeletesPctAllowed=6.836692089547961, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 34852 T99 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@651e651e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39103910)),segFN=segments_1,generation=1}
[junit4] 2> 34853 T99 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 34854 T99 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 34879 T99 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 34880 T99 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 34880 T99 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 34881 T99 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 34881 T99 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 34882 T99 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 34883 T99 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 34883 T99 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
[junit4] 2> 34884 T99 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 34884 T99 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 34885 T99 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 34886 T99 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 34886 T99 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 34887 T99 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 34887 T99 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 34888 T99 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 34889 T99 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 34890 T99 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 34891 T99 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 34893 T99 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 34893 T99 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 34895 T99 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 34896 T99 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 34896 T99 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 34897 T99 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 34898 T99 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 34900 T99 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34901 T99 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34902 T99 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34903 T99 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34904 T99 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34905 T99 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34906 T99 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34907 T99 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 34908 T99 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 34909 T99 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 34911 T99 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 34913 T99 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 34916 T99 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 34917 T99 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 34918 T99 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=58.6455078125, floorSegmentMB=1.880859375, forceMergeDeletesPctAllowed=17.713416866402458, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4407297150148548
[junit4] 2> 34919 T99 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@651e651e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39103910)),segFN=segments_1,generation=1}
[junit4] 2> 34919 T99 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 34920 T99 oass.SolrIndexSearcher.<init> Opening Searcher@4f584f58[collection1] main
[junit4] 2> 34921 T99 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 34931 T99 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 34993 T100 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4f584f58[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 35013 T99 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 35023 T89 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J1
[junit4] 2> 35024 T89 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 35033 T89 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 35037 T89 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 35039 T89 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:49643
[junit4] 2> 35045 T89 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 35046 T89 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 35047 T89 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 35048 T89 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 35067 T89 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 35123 T89 oasc.CoreContainer.<init> New CoreContainer 883831982
[junit4] 2> 35125 T89 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 35127 T89 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 35128 T89 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https
[junit4] 2> 35129 T89 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 35130 T89 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 35131 T89 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 35132 T89 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 35133 T89 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 35134 T89 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 35135 T89 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 35143 T89 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 35144 T89 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 35145 T89 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 35152 T111 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 35152 T111 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 35154 T111 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 35154 T111 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 35225 T111 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 35289 T111 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 35290 T111 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 35298 T111 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 35773 T111 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 35773 T111 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 35774 T111 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 35780 T111 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 35784 T111 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 35810 T111 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 35817 T111 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 35821 T111 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 35823 T111 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 35823 T111 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 35824 T111 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 35826 T111 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 35826 T111 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 35827 T111 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 35828 T111 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data/
[junit4] 2> 35829 T111 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3f303f30
[junit4] 2> 35831 T111 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data
[junit4] 2> 35832 T111 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data/index/
[junit4] 2> 35833 T111 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data/index' doesn't exist. Creating new index...
[junit4] 2> 35834 T111 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data/index
[junit4] 2> 35835 T111 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=77.0068359375, floorSegmentMB=0.3115234375, forceMergeDeletesPctAllowed=6.836692089547961, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 35837 T111 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6b2a6b2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3ced3ced)),segFN=segments_1,generation=1}
[junit4] 2> 35838 T111 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 35839 T111 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 35856 T111 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 35856 T111 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 35857 T111 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 35858 T111 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 35859 T111 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 35860 T111 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 35860 T111 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 35861 T111 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
[junit4] 2> 35862 T111 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 35863 T111 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 35864 T111 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 35865 T111 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 35866 T111 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 35866 T111 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 35867 T111 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 35868 T111 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 35869 T111 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 35870 T111 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 35871 T111 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 35873 T111 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 35874 T111 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 35875 T111 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 35876 T111 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 35877 T111 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 35878 T111 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 35890 T111 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 35892 T111 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35894 T111 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35896 T111 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35898 T111 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35900 T111 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35901 T111 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35903 T111 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35905 T111 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 35906 T111 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 35908 T111 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 35911 T111 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 35914 T111 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 35919 T111 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 35920 T111 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 35923 T111 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=58.6455078125, floorSegmentMB=1.880859375, forceMergeDeletesPctAllowed=17.713416866402458, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4407297150148548
[junit4] 2> 35924 T111 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6b2a6b2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3ced3ced)),segFN=segments_1,generation=1}
[junit4] 2> 35925 T111 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 35926 T111 oass.SolrIndexSearcher.<init> Opening Searcher@4bfb4bfb[collection1] main
[junit4] 2> 35928 T111 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 35940 T111 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 36007 T112 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4bfb4bfb[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 36028 T111 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 36030 T89 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J1
[junit4] 2> 36031 T89 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C52 name=collection1 org.apache.solr.core.SolrCore@7cde7cde
[junit4] 2> 36185 T97 C52 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[0 (1465157045679292416)]} 0 45
[junit4] 2> ASYNC NEW_CORE C53 name=collection1 org.apache.solr.core.SolrCore@48dd48dd
[junit4] 2> 36263 T107 C53 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[0 (1465157045798830080)]} 0 5
[junit4] 2> 36284 T97 C52 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[1 (1465157045822947328)]} 0 3
[junit4] 2> 36308 T109 C53 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[1 (1465157045848113152)]} 0 3
[junit4] 2> 36324 T97 C52 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[2 (1465157045865938944)]} 0 2
[junit4] 2> 36340 T107 C53 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[2 (1465157045881667584)]} 0 2
[junit4] 2> 36353 T97 C52 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[3 (1465157045896347648)]} 0 1
[junit4] 2> 36375 T107 C53 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[3 (1465157045919416320)]} 0 2
[junit4] 2> 36388 T97 C52 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[4 (1465157045933047808)]} 0 1
[junit4] 2> 36403 T107 C53 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[4 (1465157045948776448)]} 0 1
[junit4] 2> 36418 T97 C52 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[5 (1465157045964505088)]} 0 1
[junit4] 2> 36432 T107 C53 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[5 (1465157045979185152)]} 0 1
[junit4] 2> 36444 T97 C52 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[6 (1465157045991768064)]} 0 1
[junit4] 2> ASYNC NEW_CORE C54 name=collection1 org.apache.solr.core.SolrCore@48dd48dd
[junit4] 2> 36469 T110 C54 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[6 (1465157046005399552)]} 0 13
[junit4] 2> ASYNC NEW_CORE C55 name=collection1 org.apache.solr.core.SolrCore@7cde7cde
[junit4] 2> 36487 T95 C55 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[7 (1465157046036856832)]} 0 1
[junit4] 2> 36505 T107 C54 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[7 (1465157046056779776)]} 0 1
[junit4] 2> 36519 T95 C55 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[8 (1465157046070411264)]} 0 1
[junit4] 2> 36532 T107 C54 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[8 (1465157046084042752)]} 0 1
[junit4] 2> 36546 T95 C55 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={version=2&wt=javabin} {add=[9 (1465157046099771392)]} 0 1
[junit4] 2> 36562 T107 C54 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&version=2} {add=[9 (1465157046115500032)]} 0 1
[junit4] 2> 36577 T95 C55 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 36583 T95 C55 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@651e651e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39103910)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@651e651e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39103910)),segFN=segments_2,generation=2}
[junit4] 2> 36584 T95 C55 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 36587 T95 C55 oass.SolrIndexSearcher.<init> Opening Searcher@2fbc2fbc[collection1] main
[junit4] 2> 36588 T95 C55 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 36593 T100 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2fbc2fbc[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):C10)}
[junit4] 2> 36594 T95 C55 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={wt=javabin&softCommit=false&commit=true&version=2&waitSearcher=true} {commit=} 0 17
[junit4] 2> 36606 T107 C54 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C56 name=collection1 org.apache.solr.core.SolrCore@48dd48dd
[junit4] 2> 36621 T107 C56 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6b2a6b2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3ced3ced)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6b2a6b2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3ced3ced)),segFN=segments_2,generation=2}
[junit4] 2> 36622 T107 C56 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 36625 T107 C56 oass.SolrIndexSearcher.<init> Opening Searcher@7e937e93[collection1] main
[junit4] 2> 36626 T107 C56 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 36632 T112 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e937e93[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):C10)}
[junit4] 2> 36634 T107 C56 oasup.LogUpdateProcessor.finish [collection1] webapp=/tm_gr/h path=/update params={commit=true&version=2&waitSearcher=true&wt=javabin&softCommit=false} {commit=} 0 28
[junit4] 2> ASYNC NEW_CORE C57 name=collection1 org.apache.solr.core.SolrCore@7cde7cde
[junit4] 2> 36660 T95 C57 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={distrib=false&wt=javabin&tv.tf=true&qt=tvrh&tv=true&version=2&shards.qt=tvrh&sort=id+desc&q=id:0} hits=1 status=0 QTime=13
[junit4] 2> 36748 T108 C56 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={q=id:0&fsv=true&fl=id&version=2&tv=true&start=0&isShard=true&NOW=1397282644498&distrib=false&wt=javabin&sort=id+desc&shard.url=[ff01::114]:33332/tm_gr/h|https://127.0.0.1:49643/tm_gr/h|[ff01::083]:33332/tm_gr/h&shards.qt=tvrh&tv.tf=true&qt=tvrh&rows=10} hits=1 status=0 QTime=4
[junit4] 2> ASYNC NEW_CORE C58 name=collection1 org.apache.solr.core.SolrCore@48dd48dd
[junit4] 2> 36817 T108 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={shards.qt=tvrh&version=2&shard.url=[ff01::114]:33332/tm_gr/h|https://127.0.0.1:49643/tm_gr/h|[ff01::083]:33332/tm_gr/h&ids=0&q=id:0&wt=javabin&distrib=false&NOW=1397282644498&qt=tvrh&tv.tf=true&isShard=true&tv=true} status=0 QTime=1
[junit4] 2> 36821 T110 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={tv=true&wt=javabin&shards=[ff01::114]:33332/tm_gr/h|https://127.0.0.1:49643/tm_gr/h|[ff01::083]:33332/tm_gr/h&qt=tvrh&tv.tf=true&q=id:0&sort=id+desc&shards.qt=tvrh&version=2} hits=1 status=0 QTime=144
[junit4] 2> ASYNC NEW_CORE C59 name=collection1 org.apache.solr.core.SolrCore@7cde7cde
[junit4] 2> 36836 T96 C59 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={version=2&shards.qt=tvrh&tv.fl=test_basictv,test_offtv&fl=*,score&sort=id+asc&q=id:0&distrib=false&wt=javabin&tv.tf=true&qt=tvrh&tv=true} hits=1 status=0 QTime=1
[junit4] 2> 36862 T108 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={tv=true&start=0&isShard=true&NOW=1397282644671&distrib=false&wt=javabin&q=id:0&tv.fl=test_basictv,test_offtv&fsv=true&fl=id,score&version=2&tv.tf=true&qt=tvrh&rows=10&sort=id+asc&shard.url=https://127.0.0.1:49643/tm_gr/h|[ff01::114]:33332/tm_gr/h&shards.qt=tvrh} hits=1 status=0 QTime=1
[junit4] 2> 36875 T110 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={q=id:0&version=2&fl=*,score&tv.fl=test_basictv,test_offtv&isShard=true&tv=true&wt=javabin&distrib=false&NOW=1397282644671&shards.qt=tvrh&shard.url=https://127.0.0.1:49643/tm_gr/h|[ff01::114]:33332/tm_gr/h&ids=0&qt=tvrh&tv.tf=true} status=0 QTime=0
[junit4] 2> 36878 T109 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={wt=javabin&tv.tf=true&shards=https://127.0.0.1:49643/tm_gr/h|[ff01::114]:33332/tm_gr/h&qt=tvrh&tv=true&version=2&shards.qt=tvrh&tv.fl=test_basictv,test_offtv&fl=*,score&sort=id+asc&q=id:0} hits=1 status=0 QTime=28
[junit4] 2> 36895 T98 C59 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={sort=id+asc&q=id:0&tv.fl=test_basictv&tv.fl=test_offtv&fl=*,score&version=2&shards.qt=tvrh&tv=true&tv.tf=true&qt=tvrh&distrib=false&wt=javabin} hits=1 status=0 QTime=1
[junit4] 2> 36931 T110 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={version=2&fl=id,score&tv.fl=test_basictv&tv.fl=test_offtv&fsv=true&q=id:0&wt=javabin&distrib=false&NOW=1397282644729&isShard=true&tv=true&start=0&shards.qt=tvrh&shard.url=[ff01::114]:33332/tm_gr/h|https://127.0.0.1:49643/tm_gr/h&sort=id+asc&qt=tvrh&rows=10&tv.tf=true} hits=1 status=0 QTime=1
[junit4] 2> 36947 T107 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={distrib=false&wt=javabin&NOW=1397282644729&isShard=true&tv=true&version=2&tv.fl=test_basictv&tv.fl=test_offtv&fl=*,score&q=id:0&tv.tf=true&qt=tvrh&shards.qt=tvrh&ids=0&shard.url=[ff01::114]:33332/tm_gr/h|https://127.0.0.1:49643/tm_gr/h} status=0 QTime=1
[junit4] 2> 36951 T109 C58 oasc.SolrCore.execute [collection1] webapp=/tm_gr/h path=/select params={q=id:0&sort=id+asc&fl=*,score&tv.fl=test_basictv&tv.fl=test_offtv&shards.qt=tvrh&version=2&tv=true&shards=[ff01::114]:33332/tm_gr/h|https://127.0.0.1:49643/tm_gr/h&qt=tvrh&tv.tf=true&wt=javabin} hits=1 status=0 QTime=43
[junit4] 2> 36957 T89 oas.BaseDistributedSearchTestCase.compareSolrResponses ERROR Mismatched responses:
[junit4] 2> {responseHeader={status=0,QTime=43},response={numFound=1,start=0,maxScore=2.609438,docs=[SolrDocument{id=0, range_facet_si=0, range_facet_l=[0], range_facet_sl=[0], test_posofftv=[This is a title and another title], test_basictv=[This is a title and another title], test_notv=[This is a title and another title], test_postv=[This is a title and another title], test_offtv=[This is a title and another title], _version_=1465157045798830080, intDefault=42, timestamp=Sat Apr 12 08:04:04 EET 2014, multiDefault=[muLti-Default], score=2.609438}]},termVectors={uniqueKeyFieldName=id,0={uniqueKey=0,test_basictv={anoth={tf=1},titl={tf=2}},test_offtv={anoth={tf=1},titl={tf=2}}}}}
[junit4] 2> {responseHeader={status=0,QTime=1},response={numFound=1,start=0,maxScore=2.609438,docs=[SolrDocument{id=0, range_facet_si=0, range_facet_l=[0], range_facet_sl=[0], test_posofftv=[This is a title and another title], test_basictv=[This is a title and another title], test_notv=[This is a title and another title], test_postv=[This is a title and another title], test_offtv=[This is a title and another title], _version_=1465157045679292416, intDefault=42, timestamp=Sat Apr 12 08:04:03 EET 2014, multiDefault=[muLti-Default], score=2.609438}]},termVectors={uniqueKeyFieldName=id,0={uniqueKey=0,test_offtv={anoth={tf=1},titl={tf=2}},test_basictv={anoth={tf=1},titl={tf=2}}}}}
[junit4] 2> 36983 T89 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=561455479
[junit4] 2> 36985 T89 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7cde7cde
[junit4] 2> 37025 T89 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=1285,transaction_logs_total_number=1}
[junit4] 2> 37026 T89 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 37027 T89 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 37027 T89 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 37029 T89 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 37030 T89 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 37031 T89 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data/index;done=false>>]
[junit4] 2> 37032 T89 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data/index
[junit4] 2> 37033 T89 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data;done=false>>]
[junit4] 2> 37033 T89 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/control/data
[junit4] 2> 37035 T89 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/tm_gr/h,null}
[junit4] 2> 37111 T89 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=883831982
[junit4] 2> 37113 T89 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@48dd48dd
[junit4] 2> 37178 T89 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=1285,transaction_logs_total_number=1}
[junit4] 2> 37179 T89 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 37179 T89 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 37180 T89 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 37182 T89 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 37183 T89 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 37184 T89 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data/index;done=false>>]
[junit4] 2> 37184 T89 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data/index
[junit4] 2> 37185 T89 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data;done=false>>]
[junit4] 2> 37186 T89 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1397282641709/shard0/data
[junit4] 2> 37188 T89 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/tm_gr/h,null}
[junit4] 2> 37267 T89 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TermVectorComponentDistributedTest -Dtests.method=testDistribSearch -Dtests.seed=D050F4563AC18616 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr_CA -Dtests.timezone=Egypt -Dtests.file.encoding=UTF-8
[junit4] FAILURE 3.44s J1 | TermVectorComponentDistributedTest.testDistribSearch <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .termVectors.0.test_basictv!=test_offtv (unordered or missing)
[junit4] > at __randomizedtesting.SeedInfo.seed([D050F4563AC18616:51B67A4E4D9EE62A]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:50)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:843)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:862)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:565)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:545)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:524)
[junit4] > at org.apache.solr.handler.component.TermVectorComponentDistributedTest.doTest(TermVectorComponentDistributedTest.java:137)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:876)
[junit4] > at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 37327 T89 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=fr_CA, timezone=Egypt
[junit4] 2> NOTE: Linux 3.8.0-38-generic x86/IBM Corporation 1.6.0 (32-bit)/cpus=8,threads=1,free=5792144,total=34672128
[junit4] 2> NOTE: All tests run in this JVM: [CurrencyFieldOpenExchangeTest, XmlUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, TestFoldingMultitermQuery, TestJmxIntegration, PrimUtilsTest, EnumFieldTest, TestOmitPositions, TestIndexingPerformance, DefaultValueUpdateProcessorTest, TestBinaryResponseWriter, TestSchemaNameResource, TermVectorComponentDistributedTest]
[junit4] Completed on J1 in 3.50s, 1 test, 1 failure <<< FAILURES!
[...truncated 110 lines...]
[junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
[junit4] 2> 263800 T722 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 263801 T722 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 263807 T722 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J1/./solrtest-FullSolrCloudDistribCmdsTest-1397282871629
[junit4] 2> 263809 T722 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 263810 T723 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 263910 T722 oasc.ZkTestServer.run start zk server on port:35948
[junit4] 2> 263911 T722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 263931 T729 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@562a562a name:ZooKeeperConnection Watcher:127.0.0.1:35948 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 263933 T722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 263934 T722 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 263954 T722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 263956 T724 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x145548c99520000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2>
[junit4] 2> 263958 T731 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bfb2bfb name:ZooKeeperConnection Watcher:127.0.0.1:35948/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 263959 T722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 263959 T722 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 263971 T722 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 263982 T722 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 263990 T722 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 263999 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 264000 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 264015 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 264017 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 264026 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 264028 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 264037 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 264038 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 264046 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 264047 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 264056 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 264058 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 264092 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 264093 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 264104 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 264105 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 264114 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 264115 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 264124 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 264125 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 264134 T722 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 264135 T722 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 264147 T722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 264149 T733 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@488a488a name:ZooKeeperConnection Watcher:127.0.0.1:35948/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 264150 T722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 264360 T722 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 264366 T722 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 264371 T722 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53190
[junit4] 2> 264376 T722 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 264377 T722 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 264378 T722 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1397282871976
[junit4] 2> 264388 T722 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1397282871976/'
[junit4] 2> 264407 T722 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1397282871976/solr.xml
[junit4] 2> 264453 T722 oasc.CoreContainer.<init> New CoreContainer 1255754457
[junit4] 2> 264454 T722 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.FullSolrCloudDistribCmdsTest-controljetty-1397282871976/]
[junit4] 2> 264456 T722 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 264457 T722 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 264458 T722 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 264459 T722 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 264460 T722 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 264460 T722 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 264461 T722 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 264462 T722 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 264463 T722 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 264482 T722 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 264483 T722 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 264484 T722 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 264487 T722 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:35948/solr
[junit4] 2> 264488 T722 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 264499 T722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 264504 T744 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53255325 name:ZooKeeperConnection Watcher:127.0.0.1:35948 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 264505 T722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 264510 T722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 264512 T746 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@56355635 name:ZooKeeperConnection Watcher:127.0.0.1:35948/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 264513 T722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 264518 T722 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 264533 T722 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 264555 T722 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 264564 T722 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53190_
[junit4] 2> 264568 T722 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53190_
[junit4] 2> 264583 T722 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 264608 T722 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 264659 T722 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53190_
[junit4] 2> 264661 T722 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 264693 T722 oasc.Overseer.start Overseer (id=91572330275405828-127.0.0.1:53190_-n_0000000000) starting
[junit4] 2> 264718 T722 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 264749 T748 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 264756 T722 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 264757 T748 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 264767 T722 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 264777 T722 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 264799 T747 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 264802 T749 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 264802 T749 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 264805 T746 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 264806 T749 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 264811 T747 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 264813 T747 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53190",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53190_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2>
[...truncated too long message...]
hreadPoolExecutor.java:968)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:928)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 90) Thread[id=2208, name=TEST-ChaosMonkeySafeLeaderTest.testDistribSearch-seed#[D050F4563AC18616]-SendThread(localhost.localdomain:47236), state=BLOCKED, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at javax.security.auth.login.Configuration.getConfiguration(Configuration.java:237)
[junit4] 2> at org.apache.zookeeper.client.ZooKeeperSaslClient.clientTunneledAuthenticationInProgress(ZooKeeperSaslClient.java:487)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.clientTunneledAuthenticationInProgress(ClientCnxn.java:1241)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:360)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1068)
[junit4] 2> 91) Thread[id=2290, name=Thread-989, state=WAITING, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:167)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:1018)
[junit4] 2> 92) Thread[id=2608, name=qtp1682531401-2608 Acceptor0 SslSelectChannelConnector@127.0.0.1:46753, state=RUNNABLE, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:956)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 93) Thread[id=2200, name=qtp1740466109-2200 Acceptor0 SslSelectChannelConnector@127.0.0.1:40483, state=BLOCKED, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:956)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 94) Thread[id=2297, name=qtp452532985-2297 Acceptor0 SslSelectChannelConnector@127.0.0.1:51459, state=RUNNABLE, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:956)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 95) Thread[id=2257, name=qtp1101939118-2257 Acceptor0 SslSelectChannelConnector@127.0.0.1:49581, state=BLOCKED, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:956)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 96) Thread[id=2270, name=Thread-977, state=WAITING, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:167)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:1018)
[junit4] 2> 97) Thread[id=2272, name=coreZkRegister-1394-thread-1, state=WAITING, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:182)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1998)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:410)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:968)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:928)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 98) Thread[id=2289, name=searcherExecutor-1406-thread-1, state=WAITING, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:182)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1998)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:410)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:968)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:928)
[junit4] 2> at java.lang.Thread.run(Thread.java:761)
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=no_NO_NY, timezone=EET
[junit4] 2> NOTE: Linux 3.8.0-38-generic x86/IBM Corporation 1.6.0 (32-bit)/cpus=8,threads=125,free=96012880,total=333227008
[junit4] 2> NOTE: All tests run in this JVM: [CurrencyFieldOpenExchangeTest, XmlUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, TestFoldingMultitermQuery, TestJmxIntegration, PrimUtilsTest, EnumFieldTest, TestOmitPositions, TestIndexingPerformance, DefaultValueUpdateProcessorTest, TestBinaryResponseWriter, TestSchemaNameResource, TermVectorComponentDistributedTest, OutputWriterTest, CollectionsAPIDistributedZkTest, TestFieldTypeResource, TestDynamicFieldResource, TestComponentsName, CoreAdminCreateDiscoverTest, TestStandardQParsers, BJQParserTest, StatsComponentTest, TestNRTOpen, TestClassNameShortening, SortByFunctionTest, PreAnalyzedFieldTest, TestHighlightDedupGrouping, NoCacheHeaderTest, HdfsCollectionsAPIDistributedZkTest, TestQuerySenderListener, OpenExchangeRatesOrgProviderTest, TestStressLucene, TestStressUserVersions, BasicZkTest, ConvertedLegacyTest, TestRangeQuery, FullSolrCloudDistribCmdsTest, BlockCacheTest, TestNoOpRegenerator, BadIndexSchemaTest, AddBlockUpdateTest, HdfsUnloadDistributedZkTest, TestSolrCoreProperties, RequiredFieldsTest, CurrencyFieldXmlFileTest, SuggesterTSTTest, ZkNodePropsTest, TestRandomFaceting, SolrRequestParserTest, DocumentAnalysisRequestHandlerTest, TestCodecSupport, DistributedQueryComponentCustomSortTest, TestFileDictionaryLookup, TestAtomicUpdateErrorCases, DirectSolrSpellCheckerTest, PluginInfoTest, TestReload, TestUpdate, DisMaxRequestHandlerTest, DirectSolrConnectionTest, AlternateDirectoryTest, PathHierarchyTokenizerFactoryTest, LeaderElectionIntegrationTest, TestFastWriter, ShardRoutingCustomTest, HardAutoCommitTest, AssignTest, TestDefaultSearchFieldResource, SignatureUpdateProcessorFactoryTest, TestFieldSortValues, DocValuesMultiTest, DistanceFunctionTest, TestUniqueKeyFieldResource, TestDefaultSimilarityFactory, SpellCheckComponentTest, TestSolrQueryParserResource, TestXIncludeConfig, DirectUpdateHandlerTest, TestShortCircuitedRequests, TestZkChroot, TestNumberUtils, TestWordDelimiterFilterFactory, JSONWriterTest, TestCollationFieldDocValues, DateFieldTest, TestSolrJ, TestFastOutputStream, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeySafeLeaderTest -Dtests.seed=D050F4563AC18616 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=no_NO_NY -Dtests.timezone=EET -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | ChaosMonkeySafeLeaderTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([D050F4563AC18616]:0)
[junit4] Completed on J1 in 7225.80s, 1 test, 2 errors <<< FAILURES!
[...truncated 16 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:452: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/common-build.xml:505: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:1278: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:911: There were test failures: 365 suites, 1570 tests, 1 suite-level error, 2 errors, 4 failures, 35 ignored (7 assumptions)
Total time: 164 minutes 41 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.7-Linux (32bit/jdk1.7.0_60-ea-b13) - Build
# 100 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.7-Linux/100/
Java: 32bit/jdk1.7.0_60-ea-b13 -server -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.impl.CloudSolrServerTest.testDistribSearch
Error Message:
java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 127.0.0.1:42274 within 30000 ms
Stack Trace:
org.apache.solr.common.SolrException: java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 127.0.0.1:42274 within 30000 ms
at __randomizedtesting.SeedInfo.seed([EE0E2D926F3CE52E:6FE8A38A18638512]:0)
at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:148)
at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:99)
at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:94)
at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:85)
at org.apache.solr.cloud.AbstractZkTestCase.buildZooKeeper(AbstractZkTestCase.java:89)
at org.apache.solr.cloud.AbstractZkTestCase.buildZooKeeper(AbstractZkTestCase.java:83)
at org.apache.solr.cloud.AbstractDistribZkTestBase.setUp(AbstractDistribZkTestBase.java:70)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.setUp(AbstractFullDistribZkTestBase.java:200)
at org.apache.solr.client.solrj.impl.CloudSolrServerTest.setUp(CloudSolrServerTest.java:80)
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:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:771)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:744)
Caused by: java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 127.0.0.1:42274 within 30000 ms
at org.apache.solr.common.cloud.ConnectionManager.waitForConnected(ConnectionManager.java:223)
at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:140)
... 47 more
Build Log:
[...truncated 11863 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
[junit4] 2> 6034 T32 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 6035 T32 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /fl_fg/yn
[junit4] 2> 6037 T32 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test?
[junit4] IGNOR/A 0.04s J0 | CloudSolrServerTest.testShutdown
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple)
[junit4] 2> 6081 T32 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1397313650339
[junit4] 2> 6084 T32 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 6086 T33 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 6186 T32 oasc.ZkTestServer.run start zk server on port:42274
[junit4] 2> 6224 T32 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 38562 T36 oazsp.FileTxnLog.commit WARN fsync-ing the write ahead log in SyncThread:0 took 32325ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit4] 2> 38573 T34 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14556623ed90000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 38574 T34 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14556623ed90001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 38576 T39 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b8fe41 name:ZooKeeperConnection Watcher:127.0.0.1:42274 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 38577 T39 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4] 2> 38581 T32 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 38584 T32 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:42274 42274
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CloudSolrServerTest -Dtests.method=testDistribSearch -Dtests.seed=EE0E2D926F3CE52E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=bg_BG -Dtests.timezone=Asia/Baghdad -Dtests.file.encoding=UTF-8
[junit4] ERROR 32.6s J0 | CloudSolrServerTest.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 127.0.0.1:42274 within 30000 ms
[junit4] > at __randomizedtesting.SeedInfo.seed([EE0E2D926F3CE52E:6FE8A38A18638512]:0)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:148)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:99)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:94)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:85)
[junit4] > at org.apache.solr.cloud.AbstractZkTestCase.buildZooKeeper(AbstractZkTestCase.java:89)
[junit4] > at org.apache.solr.cloud.AbstractZkTestCase.buildZooKeeper(AbstractZkTestCase.java:83)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.setUp(AbstractDistribZkTestBase.java:70)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.setUp(AbstractFullDistribZkTestBase.java:200)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServerTest.setUp(CloudSolrServerTest.java:80)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > Caused by: java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 127.0.0.1:42274 within 30000 ms
[junit4] > at org.apache.solr.common.cloud.ConnectionManager.waitForConnected(ConnectionManager.java:223)
[junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:140)
[junit4] > ... 47 more
[junit4] 2> 38669 T32 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 32641 T31 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Appending, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=bg_BG, timezone=Asia/Baghdad
[junit4] 2> NOTE: Linux 3.8.0-38-generic i386/Oracle Corporation 1.7.0_60-ea (32-bit)/cpus=8,threads=1,free=43861032,total=64880640
[junit4] 2> NOTE: All tests run in this JVM: [NoOpResponseParserTest, SolrParamTest, SolrExceptionTest, CloudSolrServerTest]
[junit4] Completed on J0 in 32.95s, 2 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 130 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:439: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build.xml:202: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/common-build.xml:505: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:1278: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:911: There were test failures: 48 suites, 281 tests, 1 error, 1 ignored (1 assumption)
Total time: 51 minutes 26 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_60-ea-b13 -server -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.7-Linux (64bit/jdk1.6.0_45) - Build # 99 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.7-Linux/99/
Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.SyncSliceTest.testDistribSearch
Error Message:
timeout waiting to see recovered node
Stack Trace:
java.lang.AssertionError: timeout waiting to see recovered node
at __randomizedtesting.SeedInfo.seed([656503CB5EC0DF04:E4838DD3299FBF38]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SyncSliceTest.waitTillRecovered(SyncSliceTest.java:265)
at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:188)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:870)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:662)
Build Log:
[...truncated 10039 lines...]
[junit4] Suite: org.apache.solr.cloud.SyncSliceTest
[junit4] 2> 438872 T1876 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 438872 T1876 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 438875 T1876 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SyncSliceTest-1397301281258
[junit4] 2> 438876 T1876 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 438876 T1877 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 438976 T1876 oasc.ZkTestServer.run start zk server on port:36996
[junit4] 2> 438977 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 439062 T1883 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4d3d6916 name:ZooKeeperConnection Watcher:127.0.0.1:36996 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 439062 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 439063 T1876 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 439069 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 439072 T1885 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2691d0cf name:ZooKeeperConnection Watcher:127.0.0.1:36996/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 439072 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 439073 T1876 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 439078 T1876 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 439082 T1876 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 439085 T1876 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 439089 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 439090 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 439096 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 439096 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 439101 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 439101 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 439106 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 439106 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 439110 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 439111 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 439114 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 439115 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 439118 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 439119 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 439123 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 439123 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 439126 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 439127 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 439131 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 439131 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 439135 T1876 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 439136 T1876 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 439141 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 439142 T1887 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@362fe688 name:ZooKeeperConnection Watcher:127.0.0.1:36996/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 439142 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 439213 T1876 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 439216 T1876 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4] 2> 439219 T1876 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:51519
[junit4] 2> 439220 T1876 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 439220 T1876 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 439221 T1876 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526
[junit4] 2> 439221 T1876 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/'
[junit4] 2> 439239 T1876 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/solr.xml
[junit4] 2> 439273 T1876 oasc.CoreContainer.<init> New CoreContainer 49514249
[junit4] 2> 439274 T1876 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/]
[junit4] 2> 439275 T1876 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 439275 T1876 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 439276 T1876 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 439276 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 439276 T1876 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 439276 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 439277 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 439277 T1876 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 439277 T1876 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 439279 T1876 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 439280 T1876 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 439280 T1876 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 439280 T1876 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36996/solr
[junit4] 2> 439281 T1876 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 439281 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 439283 T1898 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@537d86c3 name:ZooKeeperConnection Watcher:127.0.0.1:36996 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 439283 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 439286 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 439287 T1900 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5ad24393 name:ZooKeeperConnection Watcher:127.0.0.1:36996/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 439287 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 439289 T1876 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 439293 T1876 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 439299 T1876 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 439301 T1876 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51519_
[junit4] 2> 439302 T1876 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51519_
[junit4] 2> 439308 T1876 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 439312 T1876 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 439318 T1876 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:51519_
[junit4] 2> 439318 T1876 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 439322 T1876 oasc.Overseer.start Overseer (id=91573536768655364-127.0.0.1:51519_-n_0000000000) starting
[junit4] 2> 439327 T1876 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 439332 T1902 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 439332 T1876 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 439333 T1902 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 439336 T1876 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 439338 T1876 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 439342 T1901 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 439345 T1903 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 439345 T1903 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 439347 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 439347 T1903 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 439348 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 439349 T1901 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:51519",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51519_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 439350 T1901 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 439350 T1901 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 439353 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 439354 T1900 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> 440347 T1903 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 440348 T1903 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/collection1
[junit4] 2> 440348 T1903 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 440349 T1903 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 440349 T1903 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 440351 T1903 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 440351 T1903 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/collection1/'
[junit4] 2> 440352 T1903 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/collection1/lib/classes/' to classloader
[junit4] 2> 440352 T1903 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/collection1/lib/README' to classloader
[junit4] 2> 440400 T1903 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 440444 T1903 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 440445 T1903 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 440451 T1903 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 440878 T1903 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 440879 T1903 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 440880 T1903 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 440885 T1903 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 440887 T1903 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 440903 T1903 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 440907 T1903 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 440910 T1903 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 440912 T1903 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 440912 T1903 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 440912 T1903 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 440913 T1903 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 440914 T1903 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 440914 T1903 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 440914 T1903 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-controljetty-1397301281526/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1397301281258/control/data/
[junit4] 2> 440914 T1903 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@65450f1f
[junit4] 2> 440915 T1903 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1397301281258/control/data
[junit4] 2> 440915 T1903 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1397301281258/control/data/index/
[junit4] 2> 440915 T1903 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1397301281258/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 440916 T1903 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1397301281258/control/data/index
[junit4] 2> 440916 T1903 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8368637769670227]
[junit4] 2> 440916 T1903 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@bfa164 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41ebc9ca),segFN=segments_1,generation=1}
[junit4] 2> 440917 T1903 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 440919 T1903 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 440919 T1903 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 440919 T1903 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 440919 T1903 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 440920 T1903 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 440920 T1903 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 440920 T1903 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 440920 T1903 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 440921 T1903 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 440921 T1903 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 440921 T1903 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 440921 T1903 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 440922 T1903 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 440922 T1903 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 440922 T1903 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 440922 T1903 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 440931 T1903 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 440934 T1903 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 440934 T1903 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 440935 T1903 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=21.9677734375, floorSegmentMB=2.0595703125, forceMergeDeletesPctAllowed=11.083211710036517, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 440935 T1903 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@bfa164 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41ebc9ca),segFN=segments_1,generation=1}
[junit4] 2> 440935 T1903 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 440936 T1903 oass.SolrIndexSearcher.<init> Opening Searcher@96b47eb[collection1] main
[junit4] 2> 440937 T1904 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@96b47eb[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 440939 T1903 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 440939 T1876 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0
[junit4] 2> 440940 T1876 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 440939 T1907 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:51519 collection:control_collection shard:shard1
[junit4] 2> 440942 T1907 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 440943 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 440945 T1909 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@566fce89 name:ZooKeeperConnection Watcher:127.0.0.1:36996/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 440945 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 440947 T1876 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 440953 T1876 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 440956 T1907 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 440958 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 440960 T1907 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 440960 T1907 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1702 name=collection1 org.apache.solr.core.SolrCore@741c01f3 url=https://127.0.0.1:51519/collection1 node=127.0.0.1:51519_ C1702_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:51519, core=collection1, node_name=127.0.0.1:51519_}
[junit4] 2> 440960 T1907 C1702 P51519 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:51519/collection1/
[junit4] 2> 440960 T1907 C1702 P51519 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 440961 T1907 C1702 P51519 oasc.SyncStrategy.syncToMe https://127.0.0.1:51519/collection1/ has no replicas
[junit4] 2> 440961 T1907 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:51519/collection1/ shard1
[junit4] 2> 440961 T1907 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 440961 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 440967 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 440969 T1909 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> 440969 T1900 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> 440977 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 440981 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 441025 T1876 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 441026 T1876 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 441028 T1876 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4] 2> 441029 T1876 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60127
[junit4] 2> 441031 T1876 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 441031 T1876 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 441032 T1876 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335
[junit4] 2> 441032 T1876 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/'
[junit4] 2> 441042 T1876 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/solr.xml
[junit4] 2> 441078 T1876 oasc.CoreContainer.<init> New CoreContainer 1919214462
[junit4] 2> 441078 T1876 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/]
[junit4] 2> 441079 T1876 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 441079 T1876 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 441080 T1876 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 441080 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 441080 T1876 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 441080 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 441081 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 441081 T1876 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 441081 T1876 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 441083 T1909 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> 441084 T1876 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 441084 T1900 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> 441084 T1876 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 441085 T1876 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 441085 T1876 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36996/solr
[junit4] 2> 441086 T1876 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 441086 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 441088 T1920 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e8b3874 name:ZooKeeperConnection Watcher:127.0.0.1:36996 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 441088 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 441091 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 441093 T1922 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@49596001 name:ZooKeeperConnection Watcher:127.0.0.1:36996/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 441093 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 441099 T1876 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 441125 T1907 oasc.ZkController.register We are https://127.0.0.1:51519/collection1/ and leader is https://127.0.0.1:51519/collection1/
[junit4] 2> 441125 T1907 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:51519
[junit4] 2> 441125 T1907 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 441125 T1907 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 441125 T1907 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 441127 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 441127 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 441127 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 441128 T1907 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 441129 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 441130 T1901 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:51519",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51519_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 441134 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 441237 T1922 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> 441237 T1900 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> 441237 T1909 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> 442104 T1876 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60127_
[junit4] 2> 442105 T1876 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60127_
[junit4] 2> 442109 T1909 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 442109 T1900 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 442110 T1922 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 442117 T1923 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 442117 T1923 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 442119 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 442119 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 442119 T1923 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 442119 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 442121 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 442122 T1901 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:60127",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60127_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 442122 T1901 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1]
[junit4] 2> 442123 T1901 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 442126 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 442229 T1922 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> 442229 T1909 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> 442229 T1900 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> 443120 T1923 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 443120 T1923 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/collection1
[junit4] 2> 443120 T1923 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 443121 T1923 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 443121 T1923 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 443123 T1923 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 443123 T1923 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/collection1/'
[junit4] 2> 443123 T1923 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/collection1/lib/classes/' to classloader
[junit4] 2> 443124 T1923 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/collection1/lib/README' to classloader
[junit4] 2> 443166 T1923 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 443202 T1923 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 443204 T1923 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 443210 T1923 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 443596 T1923 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 443596 T1923 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 443597 T1923 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 443602 T1923 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 443606 T1923 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 443626 T1923 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 443630 T1923 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 443634 T1923 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 443635 T1923 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 443636 T1923 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 443636 T1923 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 443637 T1923 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 443637 T1923 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 443638 T1923 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 443638 T1923 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.SyncSliceTest-jetty1-1397301283335/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty1/
[junit4] 2> 443638 T1923 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@65450f1f
[junit4] 2> 443639 T1923 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty1
[junit4] 2> 443639 T1923 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty1/index/
[junit4] 2> 443639 T1923 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 443640 T1923 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty1/index
[junit4] 2> 443640 T1923 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8368637769670227]
[junit4] 2> 443640 T1923 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@84264cf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52392d25),segFN=segments_1,generation=1}
[junit4] 2> 443641 T1923 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 443644 T1923 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 443644 T1923 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 443644 T1923 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 443645 T1923 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 443645 T1923 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 443645 T1923 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 443645 T1923 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 443646 T1923 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 443646 T1923 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 443646 T1923 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 443647 T1923 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 443647 T1923 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 443647 T1923 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 443648 T1923 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 443648 T1923 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 443649 T1923 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 443650 T1923 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 443653 T1923 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 443653 T1923 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 443654 T1923 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=21.9677734375, floorSegmentMB=2.0595703125, forceMergeDeletesPctAllowed=11.083211710036517, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 443654 T1923 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@84264cf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52392d25),segFN=segments_1,generation=1}
[junit4] 2> 443655 T1923 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 443655 T1923 oass.SolrIndexSearcher.<init> Opening Searcher@17bd38f9[collection1] main
[junit4] 2> 443658 T1924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17bd38f9[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 443660 T1923 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 443661 T1876 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0
[junit4] 2> 443661 T1876 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 443661 T1927 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:60127 collection:collection1 shard:shard1
[junit4] 2> 443663 T1927 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 443672 T1927 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 443674 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443674 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443674 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443676 T1927 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 443676 T1927 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1703 name=collection1 org.apache.solr.core.SolrCore@2d4854ce url=https://127.0.0.1:60127/collection1 node=127.0.0.1:60127_ C1703_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:60127, core=collection1, node_name=127.0.0.1:60127_}
[junit4] 2> 443676 T1927 C1703 P60127 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:60127/collection1/
[junit4] 2> 443676 T1927 C1703 P60127 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 443676 T1927 C1703 P60127 oasc.SyncStrategy.syncToMe https://127.0.0.1:60127/collection1/ has no replicas
[junit4] 2> 443677 T1927 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:60127/collection1/ shard1
[junit4] 2> 443677 T1927 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 443677 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 443684 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443688 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443694 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443734 T1876 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 443735 T1876 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 443737 T1876 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4] 2> 443738 T1876 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:49078
[junit4] 2> 443740 T1876 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 443740 T1876 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 443741 T1876 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044
[junit4] 2> 443741 T1876 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/'
[junit4] 2> 443751 T1876 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/solr.xml
[junit4] 2> 443799 T1922 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> 443799 T1900 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> 443799 T1909 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> 443800 T1876 oasc.CoreContainer.<init> New CoreContainer 2024877016
[junit4] 2> 443800 T1876 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/]
[junit4] 2> 443801 T1876 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 443801 T1876 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 443802 T1876 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 443802 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 443802 T1876 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 443802 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 443803 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 443803 T1876 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 443803 T1876 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 443805 T1876 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 443806 T1876 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 443806 T1876 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 443806 T1876 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36996/solr
[junit4] 2> 443807 T1876 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 443807 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 443809 T1938 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6b3d68ef name:ZooKeeperConnection Watcher:127.0.0.1:36996 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 443809 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 443812 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 443816 T1940 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d74cdba name:ZooKeeperConnection Watcher:127.0.0.1:36996/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 443816 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 443822 T1876 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 443840 T1927 oasc.ZkController.register We are https://127.0.0.1:60127/collection1/ and leader is https://127.0.0.1:60127/collection1/
[junit4] 2> 443841 T1927 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:60127
[junit4] 2> 443841 T1927 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 443841 T1927 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 443841 T1927 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 443842 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443842 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443843 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443844 T1927 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 443846 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 443847 T1901 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:60127",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60127_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 443850 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 443953 T1922 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> 443953 T1909 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> 443953 T1940 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> 443953 T1900 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> 444827 T1876 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49078_
[junit4] 2> 444830 T1876 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49078_
[junit4] 2> 444837 T1909 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 444837 T1940 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 444837 T1900 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 444838 T1922 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 444846 T1941 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 444846 T1941 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 444847 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 444848 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 444848 T1941 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 444848 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 444850 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 444851 T1901 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:49078",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49078_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 444851 T1901 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4] 2> 444851 T1901 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 444854 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 444957 T1922 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 444957 T1900 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 444957 T1909 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 444957 T1940 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 445848 T1941 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 445849 T1941 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/collection1
[junit4] 2> 445849 T1941 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 445850 T1941 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 445851 T1941 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 445853 T1941 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 445853 T1941 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/collection1/'
[junit4] 2> 445854 T1941 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/collection1/lib/classes/' to classloader
[junit4] 2> 445855 T1941 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/collection1/lib/README' to classloader
[junit4] 2> 445888 T1941 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 445933 T1941 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 445935 T1941 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 445942 T1941 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 446310 T1941 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 446310 T1941 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 446311 T1941 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 446316 T1941 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 446318 T1941 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 446337 T1941 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 446342 T1941 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 446347 T1941 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 446349 T1941 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 446349 T1941 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 446349 T1941 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 446351 T1941 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 446352 T1941 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 446352 T1941 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 446352 T1941 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty2-1397301286044/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty2/
[junit4] 2> 446352 T1941 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@65450f1f
[junit4] 2> 446353 T1941 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty2
[junit4] 2> 446353 T1941 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty2/index/
[junit4] 2> 446354 T1941 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 446354 T1941 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty2/index
[junit4] 2> 446354 T1941 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8368637769670227]
[junit4] 2> 446355 T1941 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c5b933e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f7ac26a),segFN=segments_1,generation=1}
[junit4] 2> 446355 T1941 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 446358 T1941 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 446358 T1941 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 446359 T1941 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 446359 T1941 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 446359 T1941 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 446359 T1941 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 446359 T1941 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 446360 T1941 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 446360 T1941 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 446360 T1941 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 446361 T1941 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 446361 T1941 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 446361 T1941 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 446362 T1941 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 446362 T1941 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 446362 T1941 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 446364 T1941 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 446366 T1941 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 446366 T1941 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 446367 T1941 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=21.9677734375, floorSegmentMB=2.0595703125, forceMergeDeletesPctAllowed=11.083211710036517, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 446367 T1941 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c5b933e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f7ac26a),segFN=segments_1,generation=1}
[junit4] 2> 446368 T1941 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 446368 T1941 oass.SolrIndexSearcher.<init> Opening Searcher@7a460700[collection1] main
[junit4] 2> 446370 T1942 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a460700[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 446372 T1941 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 446373 T1876 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0
[junit4] 2> 446373 T1876 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 446373 T1945 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:49078 collection:collection1 shard:shard1
[junit4] 2> 446378 T1945 oasc.ZkController.register We are https://127.0.0.1:49078/collection1/ and leader is https://127.0.0.1:60127/collection1/
[junit4] 2> 446378 T1945 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:49078
[junit4] 2> 446379 T1945 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 446379 T1945 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C1704 name=collection1 org.apache.solr.core.SolrCore@695cd478 url=https://127.0.0.1:49078/collection1 node=127.0.0.1:49078_ C1704_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:49078, core=collection1, node_name=127.0.0.1:49078_}
[junit4] 2> 446379 T1946 C1704 P49078 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 446382 T1946 C1704 P49078 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 446382 T1946 C1704 P49078 oasc.ZkController.publish publishing core=collection1 state=recovering collection=collection1
[junit4] 2> 446383 T1946 C1704 P49078 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 446383 T1945 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 446384 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 446384 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 446384 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 446386 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 446387 T1901 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "base_url":"https://127.0.0.1:49078",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49078_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 446390 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 446429 T1917 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 446456 T1876 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 446457 T1876 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 446460 T1876 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4] 2> 446461 T1876 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53163
[junit4] 2> 446462 T1876 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 446463 T1876 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 446463 T1876 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756
[junit4] 2> 446463 T1876 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756/'
[junit4] 2> 446475 T1876 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756/solr.xml
[junit4] 2> 446493 T1922 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 446493 T1900 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 446494 T1909 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 446494 T1940 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 446521 T1876 oasc.CoreContainer.<init> New CoreContainer 1127306540
[junit4] 2> 446521 T1876 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756/]
[junit4] 2> 446522 T1876 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 446522 T1876 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 446523 T1876 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 446523 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 446523 T1876 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 446523 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 446524 T1876 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 446524 T1876 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 446524 T1876 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 446526 T1876 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 446527 T1876 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 446527 T1876 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 446527 T1876 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36996/solr
[junit4] 2> 446528 T1876 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 446528 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 446544 T1957 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@42802cf name:ZooKeeperConnection Watcher:127.0.0.1:36996 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 446545 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 446566 T1876 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 446584 T1959 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@69929a46 name:ZooKeeperConnection Watcher:127.0.0.1:36996/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 446585 T1876 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 446615 T1876 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 447430 T1917 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 447430 T1917 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={coreNodeName=core_node2&onlyIfLeaderActive=true&state=recovering&nodeName=127.0.0.1:49078_&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=1001
[junit4] 2> 447623 T1876 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53163_
[junit4] 2> 447713 T1876 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53163_
[junit4] 2> 447717 T1940 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 447720 T1909 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 447720 T1900 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 447721 T1959 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 447722 T1922 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 447732 T1960 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 447732 T1960 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 447733 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 447733 T1960 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 447733 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 447735 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 447736 T1901 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 447737 T1901 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53163",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53163_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 447737 T1901 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4] 2> 447738 T1901 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 447741 T1900 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 447844 T1959 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 447844 T1909 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 447845 T1900 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 447845 T1922 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 447845 T1940 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 448734 T1960 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 448734 T1960 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756/collection1
[junit4] 2> 448734 T1960 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 448735 T1960 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 448736 T1960 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 448737 T1960 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 448737 T1960 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756/collection1/'
[junit4] 2> 448738 T1960 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756/collection1/lib/classes/' to classloader
[junit4] 2> 448738 T1960 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty3-1397301288756/collection1/lib/README' to classloader
[junit4] 2> 448790 T1960 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 448864 T1960 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 448866 T1960 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 448876 T1960 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 449271 T1960 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 449272 T1960 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 449272 T1960 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 449277 T1960 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 449280 T1960 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 449300 T1960 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 449304 T1960 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 449308 T1960 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 449309 T1960 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 449309 T1960 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 449310 T1960 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 449311 T1960 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 449311 T1960 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 449311 T1960 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 449312 T1960 oasc.SolrCore.<init> [collection1] Opening new SolrCore
[...truncated too long message...]
.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:188)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:870)
[junit4] > at java.lang.Thread.run(Thread.java:662)
[junit4] 2> 648452 T1876 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 649302 T1900 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 649420 T1940 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 649420 T1940 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> ASYNC NEW_CORE C1730 name=collection1 org.apache.solr.core.SolrCore@2025f29a url=https://127.0.0.1:53163/collection1 node=127.0.0.1:53163_ C1730_STATE=coll:collection1 core:collection1 props:{state=active, base_url=https://127.0.0.1:53163, core=collection1, node_name=127.0.0.1:53163_}
[junit4] 2> 650441 T1959 C1730 P53163 oasc.SyncStrategy.sync WARN Closed, skipping sync up.
[junit4] 2> 650441 T1959 oasc.ShardLeaderElectionContext.rejoinLeaderElection Not rejoining election because CoreContainer is shutdown
[junit4] 2> 650442 T1959 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2025f29a
[junit4] 2> 650445 T1959 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=24,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=3}
[junit4] 2> 650445 T1959 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 650446 T1959 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 650446 T1959 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 650447 T1959 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 650447 T1959 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 650447 T1959 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty3 [CachedDir<<refCount=0;path=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty3;done=false>>]
[junit4] 2> 650447 T1959 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty3
[junit4] 2> 650448 T1959 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty3/index [CachedDir<<refCount=0;path=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty3/index;done=false>>]
[junit4] 2> 650448 T1959 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty3/index
[junit4] 2> 650448 T1959 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 650448 T1959 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> ASYNC NEW_CORE C1731 name=collection1 org.apache.solr.core.SolrCore@623be634 url=https://127.0.0.1:45030/collection1 node=127.0.0.1:45030_ C1731_STATE=coll:collection1 core:collection1 props:{state=active, base_url=https://127.0.0.1:45030, core=collection1, node_name=127.0.0.1:45030_}
[junit4] 2> 650520 T1979 C1731 P45030 oasc.SyncStrategy.sync WARN Closed, skipping sync up.
[junit4] 2> 650520 T1979 oasc.ShardLeaderElectionContext.rejoinLeaderElection Not rejoining election because CoreContainer is shutdown
[junit4] 2> 650521 T1979 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@623be634
[junit4] 2> 650528 T1979 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=24,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=2}
[junit4] 2> 650528 T1979 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 650528 T1979 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 650528 T1979 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 650530 T1979 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 650530 T1979 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 650530 T1979 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty4 [CachedDir<<refCount=0;path=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty4;done=false>>]
[junit4] 2> 650530 T1979 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty4
[junit4] 2> 650530 T1979 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty4/index [CachedDir<<refCount=0;path=./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty4/index;done=false>>]
[junit4] 2> 650530 T1979 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.cloud.SyncSliceTest-1397301281258/jetty4/index
[junit4] 2> 650531 T1979 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 650531 T1979 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> 650531 T1979 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 650531 T1979 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 650531 T1979 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 650531 T1979 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 650532 T1979 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=th_TH, timezone=PRT
[junit4] 2> NOTE: Linux 3.8.0-38-generic amd64/Sun Microsystems Inc. 1.6.0_45 (64-bit)/cpus=8,threads=1,free=23976312,total=185352192
[junit4] 2> NOTE: All tests run in this JVM: [TestDocSet, ConnectionManagerTest, ParsingFieldUpdateProcessorsTest, StatelessScriptUpdateProcessorFactoryTest, HdfsUnloadDistributedZkTest, SolrInfoMBeanTest, QueryEqualityTest, TestStressUserVersions, TestCursorMarkWithoutUniqueKey, TestFileDictionaryLookup, TestLRUCache, UpdateParamsTest, TestRandomDVFaceting, DistributedTermsComponentTest, TestConfig, TestSolrQueryParser, RecoveryZkTest, ZkControllerTest, TestPerFieldSimilarity, TestDocBasedVersionConstraints, OverseerTest, AddSchemaFieldsUpdateProcessorFactoryTest, AnalysisErrorHandlingTest, DateMathParserTest, TestDocumentBuilder, ScriptEngineTest, EnumFieldTest, TestHighFrequencyDictionaryFactory, SearchHandlerTest, HdfsBasicDistributedZk2Test, SolrTestCaseJ4Test, CircularListTest, CursorPagingTest, TestFieldSortValues, TestStressVersions, TestLMJelinekMercerSimilarityFactory, SynonymTokenizerTest, TermsComponentTest, BasicDistributedZkTest, DocValuesMissingTest, TestLMDirichletSimilarityFactory, PreAnalyzedUpdateProcessorTest, SolrIndexSplitterTest, QueryResultKeyTest, FileBasedSpellCheckerTest, SystemInfoHandlerTest, HdfsRecoveryZkTest, TestBM25SimilarityFactory, CursorMarkTest, SpatialFilterTest, UnloadDistributedZkTest, TestSort, DistributedSpellCheckComponentTest, BJQParserTest, SyncSliceTest]
[junit4] Completed on J0 in 212.61s, 1 test, 1 failure <<< FAILURES!
[...truncated 712 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:439: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/common-build.xml:505: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:1278: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:911: There were test failures: 365 suites, 1570 tests, 1 failure, 35 ignored (7 assumptions)
Total time: 46 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure