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/08/15 03:34:43 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.7.0_65) - Build # 10906
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/10906/
Java: 64bit/jdk1.7.0_65 -XX:-UseCompressedOops -XX:+UseSerialGC
5 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
SolrCore.getOpenCount()==2
Stack Trace:
java.lang.RuntimeException: SolrCore.getOpenCount()==2
at __randomizedtesting.SeedInfo.seed([D193EE9645CBC8BC]:0)
at org.apache.solr.util.TestHarness.close(TestHarness.java:332)
at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:617)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:180)
at sun.reflect.GeneratedMethodAccessor30.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 13,257,744 bytes (threshold is 10,485,760). Field reference sizes (counted individually): - 14,386,168 bytes, protected static org.apache.solr.core.SolrConfig org.apache.solr.SolrTestCaseJ4.solrConfig - 13,829,576 bytes, protected static org.apache.solr.util.TestHarness$LocalRequestFactory org.apache.solr.SolrTestCaseJ4.lrf - 13,829,192 bytes, protected static org.apache.solr.util.TestHarness org.apache.solr.SolrTestCaseJ4.h - 448 bytes, private static java.util.regex.Pattern org.apache.solr.SolrTestCaseJ4.nonEscapedSingleQuotePattern - 328 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules - 312 bytes, private static java.util.regex.Pattern org.apache.solr.SolrTestCaseJ4.escapedSingleQuotePattern - 264 bytes, public static java.io.File org.apache.solr.cloud.AbstractZkTestCase.SOLRHOME - 216 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome - 144 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp - 88 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.configString - 80 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName - 80 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.schemaString
Stack Trace:
junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 13,257,744 bytes (threshold is 10,485,760). Field reference sizes (counted individually):
- 14,386,168 bytes, protected static org.apache.solr.core.SolrConfig org.apache.solr.SolrTestCaseJ4.solrConfig
- 13,829,576 bytes, protected static org.apache.solr.util.TestHarness$LocalRequestFactory org.apache.solr.SolrTestCaseJ4.lrf
- 13,829,192 bytes, protected static org.apache.solr.util.TestHarness org.apache.solr.SolrTestCaseJ4.h
- 448 bytes, private static java.util.regex.Pattern org.apache.solr.SolrTestCaseJ4.nonEscapedSingleQuotePattern
- 328 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules
- 312 bytes, private static java.util.regex.Pattern org.apache.solr.SolrTestCaseJ4.escapedSingleQuotePattern
- 264 bytes, public static java.io.File org.apache.solr.cloud.AbstractZkTestCase.SOLRHOME
- 216 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome
- 144 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp
- 88 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.configString
- 80 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName
- 80 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.schemaString
at com.carrotsearch.randomizedtesting.rules.StaticFieldsInvariantRule$1.afterAlways(StaticFieldsInvariantRule.java:127)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
4 threads leaked from SUITE scope at org.apache.solr.rest.TestManagedResourceStorage: 1) Thread[id=7041, name=Thread-2792, state=WAITING, group=TGRP-TestManagedResourceStorage] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at org.apache.solr.core.CloserThread.run(CoreContainer.java:894) 2) Thread[id=7040, name=searcherExecutor-4359-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 3) Thread[id=7043, name=coreZkRegister-4353-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 4) Thread[id=7036, name=Thread-2791, state=TIMED_WAITING, group=Overseer state updater.] at java.lang.Object.wait(Native Method) at org.apache.solr.cloud.DistributedQueue$LatchChildWatcher.await(DistributedQueue.java:266) at org.apache.solr.cloud.DistributedQueue.getChildren(DistributedQueue.java:309) at org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:582) at org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:560) at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:215) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.rest.TestManagedResourceStorage:
1) Thread[id=7041, name=Thread-2792, state=WAITING, group=TGRP-TestManagedResourceStorage]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at org.apache.solr.core.CloserThread.run(CoreContainer.java:894)
2) Thread[id=7040, name=searcherExecutor-4359-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
3) Thread[id=7043, name=coreZkRegister-4353-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
4) Thread[id=7036, name=Thread-2791, state=TIMED_WAITING, group=Overseer state updater.]
at java.lang.Object.wait(Native Method)
at org.apache.solr.cloud.DistributedQueue$LatchChildWatcher.await(DistributedQueue.java:266)
at org.apache.solr.cloud.DistributedQueue.getChildren(DistributedQueue.java:309)
at org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:582)
at org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:560)
at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:215)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([D193EE9645CBC8BC]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=7041, name=Thread-2792, state=WAITING, group=TGRP-TestManagedResourceStorage] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at org.apache.solr.core.CloserThread.run(CoreContainer.java:894) 2) Thread[id=7040, name=searcherExecutor-4359-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 3) Thread[id=7043, name=coreZkRegister-4353-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=7041, name=Thread-2792, state=WAITING, group=TGRP-TestManagedResourceStorage]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at org.apache.solr.core.CloserThread.run(CoreContainer.java:894)
2) Thread[id=7040, name=searcherExecutor-4359-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
3) Thread[id=7043, name=coreZkRegister-4353-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([D193EE9645CBC8BC]:0)
REGRESSION: org.apache.solr.schema.TestCloudSchemaless.testDistribSearch
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:48167/le_mk/cj/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:48167/le_mk/cj/collection1
at __randomizedtesting.SeedInfo.seed([D193EE9645CBC8BC:5075608E3294A880]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54)
at org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
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:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:442)
at sun.security.ssl.InputRecord.read(InputRecord.java:480)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:102)
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.executeMethod(HttpSolrServer.java:448)
... 48 more
Build Log:
[...truncated 11490 lines...]
[junit4] Suite: org.apache.solr.schema.TestCloudSchemaless
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/init-core-data-001
[junit4] 2> 681941 T1764 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 681941 T1764 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /le_mk/cj
[junit4] 2> 681945 T1764 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 681945 T1764 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 681945 T1765 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 682045 T1764 oasc.ZkTestServer.run start zk server on port:34027
[junit4] 2> 682046 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 682047 T1771 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@111979bc name:ZooKeeperConnection Watcher:127.0.0.1:34027 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 682048 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 682048 T1764 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 682053 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 682054 T1773 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f88a2a4 name:ZooKeeperConnection Watcher:127.0.0.1:34027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 682055 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 682056 T1764 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 682060 T1764 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 682064 T1764 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 682067 T1764 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 682071 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-schemaless.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 682072 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 682079 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-add-schema-fields-update-processor.xml to /configs/conf1/schema.xml
[junit4] 2> 682080 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 682084 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 682084 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 682089 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 682089 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 682094 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 682094 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 682098 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 682099 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 682103 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 682104 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 682109 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 682109 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 682114 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 682114 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 682118 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 682119 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 682122 T1764 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 682123 T1764 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 682129 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 682142 T1775 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4a04a3c9 name:ZooKeeperConnection Watcher:127.0.0.1:34027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 682142 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 682689 T1764 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 682692 T1764 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 682708 T1764 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:42748
[junit4] 2> 682711 T1764 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 682712 T1764 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 682713 T1764 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002
[junit4] 2> 682713 T1764 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/'
[junit4] 2> 682730 T1764 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/solr.xml
[junit4] 2> 682754 T1764 oasc.CoreContainer.<init> New CoreContainer 1805463751
[junit4] 2> 682754 T1764 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/]
[junit4] 2> 682755 T1764 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 682755 T1764 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 682755 T1764 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 682755 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 682756 T1764 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 682756 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 682756 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 682756 T1764 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 682756 T1764 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 682758 T1764 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 682759 T1764 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 682759 T1764 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 682759 T1764 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 682759 T1764 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:34027/solr
[junit4] 2> 682760 T1764 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 682760 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 682761 T1786 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f1d546c name:ZooKeeperConnection Watcher:127.0.0.1:34027 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 682762 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 682765 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 682766 T1788 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a8d502d name:ZooKeeperConnection Watcher:127.0.0.1:34027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 682767 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 682768 T1764 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 682772 T1764 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 682775 T1764 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 682778 T1764 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 682781 T1764 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 682784 T1764 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 682786 T1764 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42748_le_mk%2Fcj
[junit4] 2> 682786 T1764 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42748_le_mk%2Fcj
[junit4] 2> 682789 T1764 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 682791 T1764 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 682795 T1764 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:42748_le_mk%2Fcj
[junit4] 2> 682795 T1764 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 682799 T1764 oasc.Overseer.start Overseer (id=92278909464870916-127.0.0.1:42748_le_mk%2Fcj-n_0000000000) starting
[junit4] 2> 682804 T1764 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 682819 T1790 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 682820 T1764 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 682824 T1764 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 682827 T1764 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 682832 T1789 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 682837 T1791 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 682837 T1791 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 682839 T1791 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 682839 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 682842 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 682843 T1789 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:42748/le_mk/cj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42748_le_mk%2Fcj",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 682843 T1789 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 682843 T1789 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 682848 T1792 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> 683839 T1791 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 683839 T1791 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 683841 T1791 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 683841 T1791 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 683843 T1791 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 683843 T1791 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/'
[junit4] 2> 683844 T1791 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 683844 T1791 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 683845 T1791 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 683876 T1791 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 683899 T1791 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 683900 T1791 oass.ManagedIndexSchemaFactory.create The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 683901 T1791 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 683905 T1791 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 683936 T1791 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 683938 T1791 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 683939 T1791 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak
[junit4] 2> 683942 T1791 oass.ManagedIndexSchemaFactory.zkUgradeToManagedSchema After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 683942 T1791 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 683942 T1791 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 683943 T1791 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/data/
[junit4] 2> 683943 T1791 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 683944 T1791 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/data
[junit4] 2> 683944 T1791 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/data/index/
[junit4] 2> 683945 T1791 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 683945 T1791 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/data/index
[junit4] 2> 683946 T1791 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=52.7509765625, floorSegmentMB=0.7119140625, forceMergeDeletesPctAllowed=25.784369155363223, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 684034 T1791 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f58db23; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 684035 T1791 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 684036 T1791 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 684449 T1791 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 684450 T1791 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 684450 T1791 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 684450 T1791 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 684450 T1791 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 684451 T1791 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 684451 T1791 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 684451 T1791 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 684451 T1791 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 684451 T1791 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 684451 T1791 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 684452 T1791 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 684454 T1791 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 684455 T1791 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 684456 T1791 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 684457 T1791 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 684458 T1791 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 684460 T1791 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 684460 T1791 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 684461 T1791 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=80.8984375, floorSegmentMB=2.064453125, forceMergeDeletesPctAllowed=5.783094054228085, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 684462 T1791 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f58db23; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 684462 T1791 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 684462 T1791 oass.SolrIndexSearcher.<init> Opening Searcher@706f6ae1[collection1] main
[junit4] 2> 684463 T1791 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 684464 T1791 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 684464 T1791 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 684464 T1791 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 684464 T1791 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 684465 T1791 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 684465 T1791 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 684465 T1791 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 684466 T1791 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json
[junit4] 2> 684468 T1791 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json
[junit4] 2> 684468 T1791 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 684468 T1791 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 684468 T1791 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 684472 T1793 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@706f6ae1[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 684472 T1791 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 684473 T1796 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:42748/le_mk/cj collection:control_collection shard:shard1
[junit4] 2> 684474 T1764 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 684474 T1764 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 684474 T1796 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 684477 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 684479 T1798 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e716244 name:ZooKeeperConnection Watcher:127.0.0.1:34027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 684479 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 684479 T1796 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 684480 T1764 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 684481 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 684482 T1796 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 684482 T1796 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1051 name=collection1 org.apache.solr.core.SolrCore@459eeb09 url=https://127.0.0.1:42748/le_mk/cj/collection1 node=127.0.0.1:42748_le_mk%2Fcj C1051_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:42748/le_mk/cj, core=collection1, node_name=127.0.0.1:42748_le_mk%2Fcj}
[junit4] 2> 684482 T1796 C1051 P42748 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:42748/le_mk/cj/collection1/
[junit4] 2> 684482 T1796 C1051 P42748 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 684482 T1796 C1051 P42748 oasc.SyncStrategy.syncToMe https://127.0.0.1:42748/le_mk/cj/collection1/ has no replicas
[junit4] 2> 684483 T1796 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:42748/le_mk/cj/collection1/ shard1
[junit4] 2> 684483 T1764 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 684483 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 684483 T1796 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 684486 T1792 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> 684486 T1799 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> 684489 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 684491 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 684492 T1792 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> 684492 T1799 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> 684539 T1796 oasc.ZkController.register We are https://127.0.0.1:42748/le_mk/cj/collection1/ and leader is https://127.0.0.1:42748/le_mk/cj/collection1/
[junit4] 2> 684540 T1796 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:42748/le_mk/cj
[junit4] 2> 684540 T1796 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 684540 T1796 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 684540 T1796 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 684541 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 684542 T1796 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 684543 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 684543 T1789 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:42748/le_mk/cj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42748_le_mk%2Fcj",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 684646 T1799 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> 684646 T1792 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> 684790 T1764 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 684790 T1764 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 684794 T1764 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 684795 T1764 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:35577
[junit4] 2> 684797 T1764 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 684797 T1764 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 684797 T1764 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003
[junit4] 2> 684797 T1764 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/'
[junit4] 2> 684809 T1764 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/solr.xml
[junit4] 2> 684835 T1764 oasc.CoreContainer.<init> New CoreContainer 789429503
[junit4] 2> 684835 T1764 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/]
[junit4] 2> 684836 T1764 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 684836 T1764 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 684836 T1764 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 684837 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 684837 T1764 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 684837 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 684837 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 684837 T1764 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 684838 T1764 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 684839 T1764 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 684840 T1764 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 684840 T1764 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 684840 T1764 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 684841 T1764 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:34027/solr
[junit4] 2> 684841 T1764 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 684842 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 684843 T1810 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@38f4d59d name:ZooKeeperConnection Watcher:127.0.0.1:34027 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 684843 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 684845 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 684846 T1812 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5afd3f4d name:ZooKeeperConnection Watcher:127.0.0.1:34027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 684846 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 684851 T1764 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 685853 T1764 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35577_le_mk%2Fcj
[junit4] 2> 685855 T1764 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35577_le_mk%2Fcj
[junit4] 2> 685859 T1813 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 685864 T1799 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 685864 T1792 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 685868 T1814 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 685868 T1814 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 685869 T1814 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 685869 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 685870 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 685871 T1789 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35577/le_mk/cj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35577_le_mk%2Fcj",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 685871 T1789 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4]
[junit4] 2> 685872 T1789 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 685975 T1792 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> 685975 T1813 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> 685975 T1799 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> 686869 T1814 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 686870 T1814 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 686870 T1814 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 686871 T1814 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 686871 T1814 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 686871 T1814 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/'
[junit4] 2> 686872 T1814 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 686873 T1814 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 686873 T1814 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 686907 T1814 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 686929 T1814 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 686930 T1814 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 686934 T1814 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 686968 T1814 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 686969 T1814 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 686969 T1814 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 686969 T1814 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/data/
[junit4] 2> 686970 T1814 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 686970 T1814 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/data
[junit4] 2> 686970 T1814 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/data/index/
[junit4] 2> 686971 T1814 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 686971 T1814 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/data/index
[junit4] 2> 686972 T1814 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=52.7509765625, floorSegmentMB=0.7119140625, forceMergeDeletesPctAllowed=25.784369155363223, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 686975 T1814 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@751287d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 686976 T1814 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 686977 T1814 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 686978 T1814 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 686978 T1814 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 686978 T1814 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 686978 T1814 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 686978 T1814 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 686979 T1814 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 686979 T1814 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 686979 T1814 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 686979 T1814 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 686980 T1814 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 686980 T1814 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 686980 T1814 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 686983 T1814 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 686984 T1814 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 686985 T1814 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 686986 T1814 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 686987 T1814 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 686990 T1814 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 686990 T1814 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 686991 T1814 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=80.8984375, floorSegmentMB=2.064453125, forceMergeDeletesPctAllowed=5.783094054228085, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 686992 T1814 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-003/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@751287d4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 686992 T1814 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 686993 T1814 oass.SolrIndexSearcher.<init> Opening Searcher@40d8ca10[collection1] main
[junit4] 2> 686993 T1814 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 686994 T1814 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 686994 T1814 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 686994 T1814 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 686995 T1814 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 686995 T1814 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 686995 T1814 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 686996 T1814 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 686996 T1814 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 686996 T1814 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 686996 T1814 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 686998 T1815 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40d8ca10[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 686998 T1814 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 686998 T1818 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:35577/le_mk/cj collection:collection1 shard:shard2
[junit4] 2> 686999 T1764 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 686999 T1764 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 686999 T1818 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 687004 T1818 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 687005 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 687005 T1818 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 687005 T1818 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1052 name=collection1 org.apache.solr.core.SolrCore@41f346d4 url=https://127.0.0.1:35577/le_mk/cj/collection1 node=127.0.0.1:35577_le_mk%2Fcj C1052_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:35577/le_mk/cj, core=collection1, node_name=127.0.0.1:35577_le_mk%2Fcj}
[junit4] 2> 687005 T1818 C1052 P35577 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:35577/le_mk/cj/collection1/
[junit4] 2> 687006 T1818 C1052 P35577 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 687006 T1818 C1052 P35577 oasc.SyncStrategy.syncToMe https://127.0.0.1:35577/le_mk/cj/collection1/ has no replicas
[junit4] 2> 687006 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 687006 T1818 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:35577/le_mk/cj/collection1/ shard2
[junit4] 2> 687006 T1818 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 687010 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 687114 T1813 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> 687114 T1792 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> 687114 T1799 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> 687161 T1818 oasc.ZkController.register We are https://127.0.0.1:35577/le_mk/cj/collection1/ and leader is https://127.0.0.1:35577/le_mk/cj/collection1/
[junit4] 2> 687161 T1818 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:35577/le_mk/cj
[junit4] 2> 687162 T1818 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 687162 T1818 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 687162 T1818 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 687163 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 687163 T1818 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 687164 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 687165 T1789 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:35577/le_mk/cj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35577_le_mk%2Fcj",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 687268 T1813 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> 687268 T1792 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> 687268 T1799 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> 687332 T1764 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 687333 T1764 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 687336 T1764 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 687337 T1764 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:40205
[junit4] 2> 687338 T1764 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 687338 T1764 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 687339 T1764 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004
[junit4] 2> 687339 T1764 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/'
[junit4] 2> 687350 T1764 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/solr.xml
[junit4] 2> 687374 T1764 oasc.CoreContainer.<init> New CoreContainer 911523454
[junit4] 2> 687375 T1764 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/]
[junit4] 2> 687375 T1764 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 687376 T1764 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 687376 T1764 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 687376 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 687376 T1764 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 687377 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 687377 T1764 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 687377 T1764 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 687377 T1764 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 687378 T1764 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 687380 T1764 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 687380 T1764 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 687380 T1764 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 687380 T1764 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:34027/solr
[junit4] 2> 687380 T1764 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 687381 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 687382 T1829 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3644ed84 name:ZooKeeperConnection Watcher:127.0.0.1:34027 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 687383 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 687386 T1764 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 687387 T1831 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@681b5dd7 name:ZooKeeperConnection Watcher:127.0.0.1:34027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 687387 T1764 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 687401 T1764 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 688405 T1764 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40205_le_mk%2Fcj
[junit4] 2> 688407 T1764 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:40205_le_mk%2Fcj
[junit4] 2> 688411 T1813 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 688412 T1792 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 688412 T1832 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 688411 T1799 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 688421 T1833 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 688421 T1833 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 688423 T1792 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 688423 T1833 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 688425 T1789 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 688426 T1789 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40205/le_mk/cj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:40205_le_mk%2Fcj",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 688427 T1789 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4
[junit4] 2> 688427 T1789 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4
[junit4] 2> 688532 T1799 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> 688532 T1792 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> 688532 T1813 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> 688532 T1832 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> 689423 T1833 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 689424 T1833 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 689425 T1833 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 689425 T1833 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 689427 T1833 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 689427 T1833 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/'
[junit4] 2> 689429 T1833 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 689429 T1833 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 689429 T1833 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 689470 T1833 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 689511 T1833 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 689513 T1833 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 689518 T1833 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 689541 T1833 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 689542 T1833 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 689542 T1833 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 689542 T1833 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/data/
[junit4] 2> 689542 T1833 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 689543 T1833 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/data
[junit4] 2> 689543 T1833 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/data/index/
[junit4] 2> 689544 T1833 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 689544 T1833 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/data/index
[junit4] 2> 689545 T1833 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=52.7509765625, floorSegmentMB=0.7119140625, forceMergeDeletesPctAllowed=25.784369155363223, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 689554 T1833 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.schema.TestCloudSchemaless-D193EE9645CBC8BC-001/tempDir-004/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@89439a8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 689555 T1833 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 689557 T1833 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 689558 T1833 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 689558 T1833 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 689559 T1833 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 689559 T1833 oasc.Request
[...truncated too long message...]
teReplicaTest, MigrateRouteKeyTest, RemoteQueryErrorTest, SSLMigrationTest, ShardSplitTest, HdfsBasicDistributedZk2Test, HdfsRecoveryZkTest, HdfsUnloadDistributedZkTest, StressHdfsTest, CachingDirectoryFactoryTest, QueryResultKeyTest, TestInfoStreamLogging, TestNonNRTOpen, TestReloadAndDeleteDocs, TestSolrXml, TestSolrXmlPersistence, DistributedDebugComponentTest, DistributedExpandComponentTest, SuggestComponentTest, TestExpandComponent, TestManagedResourceStorage]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestManagedResourceStorage -Dtests.seed=D193EE9645CBC8BC -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_BA -Dtests.timezone=Asia/Gaza -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestManagedResourceStorage (suite) <<<
[junit4] > Throwable #1: java.lang.RuntimeException: SolrCore.getOpenCount()==2
[junit4] > at __randomizedtesting.SeedInfo.seed([D193EE9645CBC8BC]:0)
[junit4] > at org.apache.solr.util.TestHarness.close(TestHarness.java:332)
[junit4] > at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:617)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:180)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2: junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?), your test seems to hang on to approximately 13,257,744 bytes (threshold is 10,485,760). Field reference sizes (counted individually):
[junit4] > - 14,386,168 bytes, protected static org.apache.solr.core.SolrConfig org.apache.solr.SolrTestCaseJ4.solrConfig
[junit4] > - 13,829,576 bytes, protected static org.apache.solr.util.TestHarness$LocalRequestFactory org.apache.solr.SolrTestCaseJ4.lrf
[junit4] > - 13,829,192 bytes, protected static org.apache.solr.util.TestHarness org.apache.solr.SolrTestCaseJ4.h
[junit4] > - 448 bytes, private static java.util.regex.Pattern org.apache.solr.SolrTestCaseJ4.nonEscapedSingleQuotePattern
[junit4] > - 328 bytes, public static org.junit.rules.TestRule org.apache.solr.SolrTestCaseJ4.solrClassRules
[junit4] > - 312 bytes, private static java.util.regex.Pattern org.apache.solr.SolrTestCaseJ4.escapedSingleQuotePattern
[junit4] > - 264 bytes, public static java.io.File org.apache.solr.cloud.AbstractZkTestCase.SOLRHOME
[junit4] > - 216 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.testSolrHome
[junit4] > - 144 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp
[junit4] > - 88 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.configString
[junit4] > - 80 bytes, private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName
[junit4] > - 80 bytes, protected static java.lang.String org.apache.solr.SolrTestCaseJ4.schemaString
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.rest.TestManagedResourceStorage:
[junit4] > 1) Thread[id=7041, name=Thread-2792, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:503)
[junit4] > at org.apache.solr.core.CloserThread.run(CoreContainer.java:894)
[junit4] > 2) Thread[id=7040, name=searcherExecutor-4359-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 3) Thread[id=7043, name=coreZkRegister-4353-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 4) Thread[id=7036, name=Thread-2791, state=TIMED_WAITING, group=Overseer state updater.]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at org.apache.solr.cloud.DistributedQueue$LatchChildWatcher.await(DistributedQueue.java:266)
[junit4] > at org.apache.solr.cloud.DistributedQueue.getChildren(DistributedQueue.java:309)
[junit4] > at org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:582)
[junit4] > at org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:560)
[junit4] > at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:215)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([D193EE9645CBC8BC]:0)Throwable #4: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=7041, name=Thread-2792, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:503)
[junit4] > at org.apache.solr.core.CloserThread.run(CoreContainer.java:894)
[junit4] > 2) Thread[id=7040, name=searcherExecutor-4359-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 3) Thread[id=7043, name=coreZkRegister-4353-thread-1, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([D193EE9645CBC8BC]:0)
[junit4] Completed on J0 in 27.35s, 2 tests, 1 failure, 3 errors <<< FAILURES!
[...truncated 120 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:474: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:454: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1308: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:923: There were test failures: 411 suites, 1712 tests, 4 suite-level errors, 1 error, 216 ignored (17 assumptions)
Total time: 87 minutes 2 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0_65 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.7.0_65) - Build # 10907
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/10907/
Java: 64bit/jdk1.7.0_65 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes
Error Message:
key:ignored__attachment_mimetype expected:<[message/rfc822]> but was:<[text/plain]>
Stack Trace:
java.lang.AssertionError: key:ignored__attachment_mimetype expected:<[message/rfc822]> but was:<[text/plain]>
at __randomizedtesting.SeedInfo.seed([FA90C80C01189762:60536B9264C3C9B7]: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.apache.solr.morphlines.solr.AbstractSolrMorphlineTestBase.testDocumentTypesInternal(AbstractSolrMorphlineTestBase.java:170)
at org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes(SolrCellMorphlineTest.java:193)
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:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 19418 lines...]
[junit4] Suite: org.apache.solr.morphlines.cell.SolrCellMorphlineTest
[junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001
[junit4] 2> 2811 T12 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 2860 T12 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 3056 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 3921 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 3924 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 3925 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 3926 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 3927 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 3928 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 3929 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 3931 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 3932 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 3933 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 4128 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 4608 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4620 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4727 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 4959 T12 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.IntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4969 T12 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.LongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4979 T12 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.FloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4990 T12 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4999 T12 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 5557 T12 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.ThaiWordFilterFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 5612 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 5916 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 5933 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 6416 T12 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6417 T12 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr
[junit4] 2> 6418 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/'
[junit4] 2> 6559 T12 oasc.CoreContainer.<init> New CoreContainer 227104598
[junit4] 2> 6560 T12 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/]
[junit4] 2> 6588 T12 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 6588 T12 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 6589 T12 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 6590 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6590 T12 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6591 T12 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6591 T12 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6592 T12 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6593 T12 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 7513 T12 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 7573 T12 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 7575 T12 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 7578 T12 oasc.CoreContainer.load Host Name:
[junit4] 2> 8499 T13 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 8582 T13 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 8583 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 8584 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8585 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 8586 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8586 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 8587 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8588 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 8589 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 8590 T13 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 8651 T13 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 8737 T13 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 8738 T13 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 8799 T13 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 9259 T13 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 9290 T13 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 9299 T13 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 9384 T13 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/
[junit4] 2> 9454 T13 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 9473 T13 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/
[junit4] 2> 9491 T13 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 9509 T13 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 9510 T13 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 2> 9549 T13 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001
[junit4] 2> 9550 T13 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index/
[junit4] 2> 9551 T13 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 9562 T13 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index
[junit4] 2> 9774 T13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7dc2445f lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index),segFN=segments_1,generation=1}
[junit4] 2> 9775 T13 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 9791 T13 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 9791 T13 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 9793 T13 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 9814 T13 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 9815 T13 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 10207 T13 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 10246 T13 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 10246 T13 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 10247 T13 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 10247 T13 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 10269 T13 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 10270 T13 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4] 2> 10276 T13 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 10277 T13 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4] 2> 10282 T13 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 10283 T13 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 10283 T13 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 10285 T13 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 10286 T13 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 10287 T13 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 10294 T13 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4] 2> 10301 T13 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 10308 T13 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 10332 T13 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 10333 T13 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 10333 T13 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4] 2> 10334 T13 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 10335 T13 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4] 2> 10335 T13 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 10336 T13 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4] 2> 10336 T13 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 10337 T13 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4] 2> 10440 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 10448 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 10449 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 10451 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 10452 T13 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 10495 T13 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 60000ms;
[junit4] 2> 10496 T13 oasu.CommitTracker.<init> Soft AutoCommit: if uncommited for 1000ms;
[junit4] 2> 10500 T13 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7dc2445f lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index),segFN=segments_1,generation=1}
[junit4] 2> 10501 T13 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 10523 T13 oass.SolrIndexSearcher.<init> Opening Searcher@52b8e429[collection1] main
[junit4] 2> 10551 T13 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 10555 T13 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 10636 T13 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 10637 T13 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 10642 T13 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 10643 T13 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 10643 T13 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 10670 T13 oass.DirectSolrSpellChecker.init init: {name=default,field=name,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 10698 T13 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4] 2> 10704 T13 oashc.QueryElevationComponent.inform Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 10810 T13 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 10811 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@52b8e429[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 10812 T13 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 10822 T12 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 10878 T12 oas.SolrTestCaseJ4.setUp ###Starting testSolrCellDocumentTypes
[junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 11021 T14 C0 oasc.SolrCore.execute [collection1] webapp=null path=null params={distrib=false&event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml} hits=0 status=0 QTime=206
[junit4] 2> 11024 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 11025 T14 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 11025 T14 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 11026 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52b8e429[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 11242 T12 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1476472959195414528)} 0 343
[junit4] 2> 11245 T12 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11246 T12 C0 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11247 T12 C0 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 11248 T12 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 11249 T12 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 4
[junit4] 2> 11649 T12 okma.MorphlineContext.importCommandBuilders Importing commands
[junit4] 2> 15273 T12 okma.MorphlineContext.importCommandBuilders Done importing commands
[junit4] 2> 22143 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 22226 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 22227 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 22229 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 22230 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 22231 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 22233 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 22234 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 22235 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 22236 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 22237 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 22291 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 22426 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 22427 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 22489 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 23043 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 23083 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 23092 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 23152 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 23218 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 23219 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 23220 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 23221 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 23222 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 23223 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 23224 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 23225 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 23226 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 23227 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 23275 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 23376 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 23377 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 23437 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 23926 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 23967 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 23978 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 24864 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 24912 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 24913 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 24914 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 24914 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 24915 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 24916 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 24916 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 24917 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 24917 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 24918 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 24959 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 25020 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 25021 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 25053 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 25415 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 25447 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 25453 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 25495 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 25546 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 25547 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 25548 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 25549 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 25549 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 25550 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 25550 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 25551 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 25552 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 25552 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 25601 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 25659 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 25660 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 25686 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 26053 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 26144 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 26149 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 26502 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 26541 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 26542 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 26542 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 26543 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 26543 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 26544 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 26545 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 26545 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 26546 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 26546 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 26587 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 26653 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 26654 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 26677 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 27058 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 27083 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 27088 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 27151 T12 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1476472976046030848)} 0 33
[junit4] 2> 27152 T12 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 27153 T12 C1 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 27153 T12 C1 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 27154 T12 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 27154 T12 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 2
[junit4] 2> 27821 T12 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testBMPfp.txt#0 (1476472976685662208)]} 0 94
[junit4] 2> 27825 T12 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 27943 T12 C2 oass.SolrIndexSearcher.<init> Opening Searcher@5fb1e1d4[collection1] main
[junit4] 2> 27946 T12 C2 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 27947 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5fb1e1d4[collection1] main{StandardDirectoryReader(segments_1:3:nrt _0(4.10):C1)}
[junit4] 2> 27948 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 27948 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5fb1e1d4[collection1] main{StandardDirectoryReader(segments_1:3:nrt _0(4.10):C1)}
[junit4] 2> 27949 T12 C2 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 124
[junit4] 2> 27966 T12 C2 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=1 status=0 QTime=14
[junit4] 2> 28113 T12 C2 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[boilerplate.html#0 (1476472977084121088)]} 0 5
[junit4] 2> 28114 T12 C2 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 28160 T12 C2 oass.SolrIndexSearcher.<init> Opening Searcher@5b3c635b[collection1] main
[junit4] 2> 28161 T12 C2 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 28161 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5b3c635b[collection1] main{StandardDirectoryReader(segments_1:5:nrt _0(4.10):C1 _1(4.10):C1)}
[junit4] 2> 28162 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 28162 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5b3c635b[collection1] main{StandardDirectoryReader(segments_1:5:nrt _0(4.10):C1 _1(4.10):C1)}
[junit4] 2> 28163 T12 C2 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 49
[junit4] 2> 28164 T12 C2 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=2 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 29952 T12 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[NullHeader.docx#0 (1476472979000918016)]} 0 17
[junit4] 2> 29953 T12 C3 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 29988 T12 C3 oass.SolrIndexSearcher.<init> Opening Searcher@3198c2ae[collection1] main
[junit4] 2> 29989 T12 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 29989 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3198c2ae[collection1] main{StandardDirectoryReader(segments_1:7:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1)}
[junit4] 2> 29990 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 29991 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3198c2ae[collection1] main{StandardDirectoryReader(segments_1:7:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1)}
[junit4] 2> 29991 T12 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 38
[junit4] 2> 29992 T12 C3 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=3 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 30855 T12 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testWORD_various.doc#0 (1476472979954073600)]} 0 14
[junit4] 2> 30858 T12 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 30870 T12 C4 oass.SolrIndexSearcher.<init> Opening Searcher@4af32058[collection1] main
[junit4] 2> 30870 T12 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 30871 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4af32058[collection1] main{StandardDirectoryReader(segments_1:9:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1)}
[junit4] 2> 30871 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 30872 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4af32058[collection1] main{StandardDirectoryReader(segments_1:9:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1)}
[junit4] 2> 30873 T12 C4 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 16
[junit4] 2> 30874 T12 C4 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=4 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C5 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 32673 T12 C5 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testPDF.pdf#0 (1476472981864579072)]} 0 7
[junit4] 2> 32675 T12 C5 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 32686 T12 C5 oass.SolrIndexSearcher.<init> Opening Searcher@4c730ca2[collection1] main
[junit4] 2> 32687 T12 C5 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 32687 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4c730ca2[collection1] main{StandardDirectoryReader(segments_1:11:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1)}
[junit4] 2> 32688 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 32689 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c730ca2[collection1] main{StandardDirectoryReader(segments_1:11:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1)}
[junit4] 2> 32689 T12 C5 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 15
[junit4] 2> 32690 T12 C5 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=5 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C6 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 32966 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testJPEG_EXIF.jpg#0 (1476472982166568960)]} 0 15
[junit4] 2> 32967 T12 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 32982 T12 C6 oass.SolrIndexSearcher.<init> Opening Searcher@22815b70[collection1] main
[junit4] 2> 32982 T12 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 32983 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@22815b70[collection1] main{StandardDirectoryReader(segments_1:13:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1)}
[junit4] 2> 32983 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 32984 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@22815b70[collection1] main{StandardDirectoryReader(segments_1:13:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1)}
[junit4] 2> 32985 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 18
[junit4] 2> 32986 T12 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=6 status=0 QTime=0
[junit4] 2> 33030 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testJPEG_EXIF.jpg.gz#0 (1476472982230532096)]} 0 17
[junit4] 2> 33031 T12 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 33043 T12 C6 oass.SolrIndexSearcher.<init> Opening Searcher@6c0ce8ee[collection1] main
[junit4] 2> 33044 T12 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 33044 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6c0ce8ee[collection1] main{StandardDirectoryReader(segments_1:15:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1)}
[junit4] 2> 33045 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 33046 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6c0ce8ee[collection1] main{StandardDirectoryReader(segments_1:15:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1)}
[junit4] 2> 33047 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 16
[junit4] 2> 33048 T12 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=7 status=0 QTime=1
[junit4] 2> 33084 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[797aa2b8-3dc8-473e-a646-d0ca64caf85b (1476472982291349504)]} 0 14
[junit4] 2> 33086 T12 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 33097 T12 C6 oass.SolrIndexSearcher.<init> Opening Searcher@3cf32538[collection1] main
[junit4] 2> 33098 T12 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 33098 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3cf32538[collection1] main{StandardDirectoryReader(segments_1:17:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1)}
[junit4] 2> 33099 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 33100 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3cf32538[collection1] main{StandardDirectoryReader(segments_1:17:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1)}
[junit4] 2> 33101 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 15
[junit4] 2> 33102 T12 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=8 status=0 QTime=0
[junit4] 2> 33128 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testXML.xml#0 (1476472982345875456)]} 0 3
[junit4] 2> 33129 T12 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 33136 T12 C6 oass.SolrIndexSearcher.<init> Opening Searcher@27f8c72d[collection1] main
[junit4] 2> 33137 T12 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 33137 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@27f8c72d[collection1] main{StandardDirectoryReader(segments_1:19:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1)}
[junit4] 2> 33138 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 33139 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@27f8c72d[collection1] main{StandardDirectoryReader(segments_1:19:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1)}
[junit4] 2> 33140 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 11
[junit4] 2> 33141 T12 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=9 status=0 QTime=0
[junit4] 2> 33148 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[aa7bedcc-4de5-401a-b756-68fc6d609116 (1476472982367895552)]} 0 1
[junit4] 2> 33150 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[1d3a6400-89db-4d6c-9e66-262fb7df42e2 (1476472982371041280)]} 0 1
[junit4] 2> 33151 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[e8a6b0a6-6b1b-4c5d-bcc2-4e1db36e7a65 (1476472982372089856)]} 0 0
[junit4] 2> 33153 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[b466614d-d35b-4b7b-abb8-51325fcf9f51 (1476472982373138432)]} 0 1
[junit4] 2> 33154 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[02698c05-e9a8-4a7c-bb55-ca152e1d69fc (1476472982375235584)]} 0 0
[junit4] 2> 33156 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[58b7608f-ecc5-41dd-8936-31ca8f8bbd34 (1476472982376284160)]} 0 1
[junit4] 2> 33156 T12 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 33173 T12 C6 oass.SolrIndexSearcher.<init> Opening Searcher@7b06a4[collection1] main
[junit4] 2> 33174 T12 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 33174 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7b06a4[collection1] main{StandardDirectoryReader(segments_1:21:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1 _9(4.10):C6)}
[junit4] 2> 33175 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 33176 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b06a4[collection1] main{StandardDirectoryReader(segments_1:21:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1 _9(4.10):C6)}
[junit4] 2> 33178 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 22
[junit4] 2> 33179 T12 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=15 status=0 QTime=0
[junit4] 2> 33198 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[5c777388-af62-4675-904f-308c4d4edbbf (1476472982420324352)]} 0 2
[junit4] 2> 33201 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[05346a75-86ea-45dd-acaa-f5cedb1cf321 (1476472982423470080)]} 0 1
[junit4] 2> 33203 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[8cdd4c80-0b06-442c-a422-9b692eaf5ff2 (1476472982426615808)]} 0 1
[junit4] 2> 33205 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[239ec0b1-a5d7-4a3e-b74a-088bccd90e1d (1476472982428712960)]} 0 1
[junit4] 2> 33207 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[35f74231-e465-4a21-a9b4-f777885af704 (1476472982430810112)]} 0 0
[junit4] 2> 33210 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[15e461c9-dbc5-4203-857f-ed0168b7a257 (1476472982432907264)]} 0 1
[junit4] 2> 33211 T12 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 33223 T12 C6 oass.SolrIndexSearcher.<init> Opening Searcher@55dc4df[collection1] main
[junit4] 2> 33224 T12 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 33224 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@55dc4df[collection1] main{StandardDirectoryReader(segments_1:24:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1 _9(4.10):C6 _b(4.10):C6)}
[junit4] 2> 33225 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 33226 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@55dc4df[collection1] main{StandardDirectoryReader(segments_1:24:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1 _9(4.10):C6 _b(4.10):C6)}
[junit4] 2> 33227 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 16
[junit4] 2> 33228 T12 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=21 status=0 QTime=1
[junit4] 2> 33245 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[875f9a4b-27f8-4cbd-be51-a5536a154f7d (1476472982469607424)]} 0 2
[junit4] 2> 33248 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[37e0bacd-434e-4ec9-a64b-cb67f3b07387 (1476472982472753152)]} 0 1
[junit4] 2> 33253 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[60e821a7-bcbc-4e9e-82cc-f0d0b2617e58 (1476472982477996032)]} 0 1
[junit4] 2> 33256 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[383e01d3-3112-40c3-82cf-cfececb57d8c (1476472982481141760)]} 0 1
[junit4] 2> 33257 T12 C6 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 33269 T12 C6 oass.SolrIndexSearcher.<init> Opening Searcher@25b8fc77[collection1] main
[junit4] 2> 33270 T12 C6 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 33270 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@25b8fc77[collection1] main{StandardDirectoryReader(segments_1:26:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1 _9(4.10):C6 _b(4.10):C6 _c(4.10):C4)}
[junit4] 2> 33272 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 33272 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25b8fc77[collection1] main{StandardDirectoryReader(segments_1:26:nrt _0(4.10):C1 _1(4.10):C1 _2(4.10):C1 _3(4.10):C1 _4(4.10):C1 _5(4.10):C1 _6(4.10):C1 _7(4.10):C1 _8(4.10):C1 _9(4.10):C6 _b(4.10):C6 _c(4.10):C4)}
[junit4] 2> 33274 T12 C6 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 17
[junit4] 2> 33275 T12 C6 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=25 status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C7 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 34261 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[1234567890 (1476472983534960640)]} 0 1
[junit4] 2> 34264 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[2345678901 (1476472983538106368)]} 0 1
[junit4] 2> 34265 T12 C7 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 34273 T12 C7 oass.SolrIndexSearcher.<init> Opening Searcher@70fd8b50[collection1] main
[junit4] 2> 34274 T12 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 34274 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@70fd8b50[collection1] main{StandardDirectoryReader(segments_1:29:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2)}
[junit4] 2> 34275 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 34275 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@70fd8b50[collection1] main{StandardDirectoryReader(segments_1:29:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2)}
[junit4] 2> 34280 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 16
[junit4] 2> 34281 T12 C7 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=27 status=0 QTime=1
[junit4] 2> 34340 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[1234567891 (1476472983615700992)]} 0 3
[junit4] 2> 34344 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[2345678902 (1476472983621992448)]} 0 2
[junit4] 2> 34345 T12 C7 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 34356 T12 C7 oass.SolrIndexSearcher.<init> Opening Searcher@49e4e71b[collection1] main
[junit4] 2> 34358 T12 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 34358 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@49e4e71b[collection1] main{StandardDirectoryReader(segments_1:31:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2)}
[junit4] 2> 34359 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 34359 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@49e4e71b[collection1] main{StandardDirectoryReader(segments_1:31:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2)}
[junit4] 2> 34360 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 15
[junit4] 2> 34361 T12 C7 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=29 status=0 QTime=0
[junit4] 2> 34379 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[5234567890 (1476472983655546880)]} 0 5
[junit4] 2> 34383 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[6345678901 (1476472983662886912)]} 0 2
[junit4] 2> 34384 T12 C7 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 34395 T12 C7 oass.SolrIndexSearcher.<init> Opening Searcher@e1bcb1d[collection1] main
[junit4] 2> 34396 T12 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 34396 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@e1bcb1d[collection1] main{StandardDirectoryReader(segments_1:33:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2 _f(4.10):C2)}
[junit4] 2> 34397 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 34398 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e1bcb1d[collection1] main{StandardDirectoryReader(segments_1:33:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2 _f(4.10):C2)}
[junit4] 2> 34398 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 14
[junit4] 2> 34399 T12 C7 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=31 status=0 QTime=0
[junit4] 2> 34426 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[3234567890 (1476472983702732800)]} 0 6
[junit4] 2> 34430 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[4345678901 (1476472983712169984)]} 0 2
[junit4] 2> 34431 T12 C7 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 34440 T12 C7 oass.SolrIndexSearcher.<init> Opening Searcher@20de83dc[collection1] main
[junit4] 2> 34441 T12 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 34441 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@20de83dc[collection1] main{StandardDirectoryReader(segments_1:35:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2 _f(4.10):C2 _g(4.10):C2)}
[junit4] 2> 34442 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 34442 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@20de83dc[collection1] main{StandardDirectoryReader(segments_1:35:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2 _f(4.10):C2 _g(4.10):C2)}
[junit4] 2> 34443 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 13
[junit4] 2> 34444 T12 C7 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=33 status=0 QTime=0
[junit4] 2> 34460 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[email.eml#0 (1476472983741530112)]} 0 4
[junit4] 2> 34461 T12 C7 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 34468 T12 C7 oass.SolrIndexSearcher.<init> Opening Searcher@7251bfaf[collection1] main
[junit4] 2> 34469 T12 C7 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 34469 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7251bfaf[collection1] main{StandardDirectoryReader(segments_1:37:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2 _f(4.10):C2 _g(4.10):C2 _h(4.10):C1)}
[junit4] 2> 34469 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 34470 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7251bfaf[collection1] main{StandardDirectoryReader(segments_1:37:nrt _a(4.10):C15 _b(4.10):C6 _c(4.10):C4 _d(4.10):C2 _e(4.10):C2 _f(4.10):C2 _g(4.10):C2 _h(4.10):C1)}
[junit4] 2> 34471 T12 C7 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 10
[junit4] 2> 34472 T12 C7 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=34 status=0 QTime=1
[junit4] 2> 34478 T12 oas.SolrTestCaseJ4.tearDown ###Ending testSolrCellDocumentTypes
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrCellMorphlineTest -Dtests.method=testSolrCellDocumentTypes -Dtests.seed=FA90C80C01189762 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=tr -Dtests.timezone=America/Cancun -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 23.8s | SolrCellMorphlineTest.testSolrCellDocumentTypes <<<
[junit4] > Throwable #1: java.lang.AssertionError: key:ignored__attachment_mimetype expected:<[message/rfc822]> but was:<[text/plain]>
[junit4] > at __randomizedtesting.SeedInfo.seed([FA90C80C01189762:60536B9264C3C9B7]:0)
[junit4] > at org.apache.solr.morphlines.solr.AbstractSolrMorphlineTestBase.testDocumentTypesInternal(AbstractSolrMorphlineTestBase.java:170)
[junit4] > at org.apache.solr.morphlines.cell.SolrCellMorphlineTest.testSolrCellDocumentTypes(SolrCellMorphlineTest.java:193)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 34704 T12 oas.SolrTestCaseJ4.setUp ###Starting testTransformValue
[junit4] 2> ASYNC NEW_CORE C8 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 34755 T12 C8 oass.SolrIndexSearcher.<init> Opening Searcher@782942d0[collection1] realtime
[junit4] 2> 34756 T12 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1476472984001576960)} 0 51
[junit4] 2> 34757 T12 C8 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 34758 T12 C8 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7dc2445f lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7dc2445f lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index),segFN=segments_2,generation=2}
[junit4] 2> 34759 T12 C8 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 34760 T12 C8 oass.SolrIndexSearcher.<init> Opening Searcher@4d8b352f[collection1] main
[junit4] 2> 34761 T12 C8 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 34761 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4d8b352f[collection1] main{StandardDirectoryReader(segments_1:39:nrt)}
[junit4] 2> 34762 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 34762 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4d8b352f[collection1] main{StandardDirectoryReader(segments_1:39:nrt)}
[junit4] 2> 34767 T12 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 10
[junit4] 2> 34790 T12 oas.SolrTestCaseJ4.tearDown ###Ending testTransformValue
[junit4] 2> 34809 T12 oas.SolrTestCaseJ4.setUp ###Starting testSolrCellXML
[junit4] 2> 34812 T12 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1476472984111677440)} 0 2
[junit4] 2> 34813 T12 C8 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 34813 T12 C8 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 34814 T12 C8 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 34815 T12 C8 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 34816 T12 C8 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {commit=} 0 3
[junit4] 2> 34837 T12 okma.MorphlineContext.importCommandBuilders Importing commands
[junit4] 2> 36705 T12 okma.MorphlineContext.importCommandBuilders Done importing commands
[junit4] 2> 36730 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 36777 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 36778 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 36779 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 36779 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 36780 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 36780 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 36781 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 36781 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 36782 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 36782 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 36825 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 36893 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 36895 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 36920 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 37308 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 37331 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 37336 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 37374 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 37409 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 37409 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 37410 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37410 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 37411 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37411 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 37412 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37412 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 37413 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37413 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 37452 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 37513 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 37514 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 37535 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 37896 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 37917 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 37921 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 37943 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/'
[junit4] 2> 37974 T12 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 37974 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 37975 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37975 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 37976 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37976 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 37977 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37977 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 37978 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/contrib/morphlines-core/src/test-files/solr/collection1/../../../dist).
[junit4] 2> 37978 T12 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4] 2> 38014 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 38069 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 38070 T12 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 38081 T12 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 38458 T12 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 38479 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 38484 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> ASYNC NEW_CORE C9 name=collection1 org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 38529 T12 C9 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/upd
[...truncated too long message...]
ion1] Registered new searcher Searcher@38ea9a7b[collection1] main{StandardDirectoryReader(segments_4:97:nrt _18(4.10):C1)}
[junit4] 2> 50556 T12 C16 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 6
[junit4] 2> 50557 T12 C16 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=1 status=0 QTime=0
[junit4] 2> 50578 T12 C16 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[testJPEG_EXIF.jpg.gz#0 (1476473000643526656)]} 0 4
[junit4] 2> 50579 T12 C16 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 50582 T12 C16 oass.SolrIndexSearcher.<init> Opening Searcher@7f139040[collection1] main
[junit4] 2> 50583 T12 C16 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 50583 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7f139040[collection1] main{StandardDirectoryReader(segments_4:99:nrt _18(4.10):C1 _19(4.10):C1)}
[junit4] 2> 50584 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 50584 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f139040[collection1] main{StandardDirectoryReader(segments_4:99:nrt _18(4.10):C1 _19(4.10):C1)}
[junit4] 2> 50584 T12 C16 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 5
[junit4] 2> 50585 T12 C16 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=2 status=0 QTime=0
[junit4] 2> 50603 T12 C16 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[696a89ad-a56a-4996-8b4c-5b517671569f#0 (1476473000669741056)]} 0 4
[junit4] 2> 50604 T12 C16 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 50609 T12 C16 oass.SolrIndexSearcher.<init> Opening Searcher@29aad718[collection1] main
[junit4] 2> 50610 T12 C16 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 50610 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@29aad718[collection1] main{StandardDirectoryReader(segments_4:101:nrt _18(4.10):C1 _19(4.10):C1 _1a(4.10):C1)}
[junit4] 2> 50610 T14 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 50611 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29aad718[collection1] main{StandardDirectoryReader(segments_4:101:nrt _18(4.10):C1 _19(4.10):C1 _1a(4.10):C1)}
[junit4] 2> 50611 T12 C16 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 7
[junit4] 2> 50612 T12 C16 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=3 status=0 QTime=0
[junit4] 2> 50614 T12 C16 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 50614 T12 C16 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 50615 T12 C16 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 50615 T12 C16 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=true&waitSearcher=true} {commit=} 0 2
[junit4] 2> 50616 T12 C16 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*&rows=2147483647} hits=3 status=0 QTime=0
[junit4] 2> 50617 T12 oas.SolrTestCaseJ4.tearDown ###Ending testSolrCellJPGCompressed
[junit4] 2> 50620 T12 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 50620 T12 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=227104598
[junit4] 2> 50621 T12 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3badb7a0
[junit4] 2> 50622 T12 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=56,autocommit maxTime=60000ms,autocommits=0,soft autocommit maxTime=1000ms,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=61,cumulative_deletesById=0,cumulative_deletesByQuery=9,cumulative_errors=0,transaction_logs_total_size=118824,transaction_logs_total_number=9}
[junit4] 2> 50623 T12 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 50623 T12 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 50623 T12 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 50624 T12 C16 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close.
[junit4] 2> 50625 T12 C16 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7dc2445f lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7dc2445f lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index),segFN=segments_5,generation=5}
[junit4] 2> 50626 T12 C16 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 50628 T12 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 50629 T12 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 50661 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index;done=false>>]
[junit4] 2> 50662 T12 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001/index
[junit4] 2> 50662 T12 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001;done=false>>]
[junit4] 2> 50663 T12 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001/init-core-data-001
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/contrib/solr-morphlines-cell/test/J0/./solr.morphlines.cell.SolrCellMorphlineTest-FA90C80C01189762-001
[junit4] 2> NOTE: test params are: codec=Lucene45, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=tr, timezone=America/Cancun
[junit4] 2> NOTE: Linux 3.13.0-32-generic amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=8,threads=1,free=62594152,total=160808960
[junit4] 2> NOTE: All tests run in this JVM: [SolrCellMorphlineTest]
[junit4] Completed in 51.52s, 5 tests, 1 failure <<< FAILURES!
[...truncated 9 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:474: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:454: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:209: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:440: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1308: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:923: There were test failures: 1 suite, 5 tests, 1 failure
Total time: 104 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0_65 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any