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 2015/01/07 16:05:10 UTC
[JENKINS] Lucene-Solr-5.x-Linux (32bit/jdk1.7.0_67) - Build # 11696
- Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11696/
Java: 32bit/jdk1.7.0_67 -client -XX:+UseSerialGC (asserts: true)
3 tests failed.
FAILED: org.apache.solr.client.solrj.SolrSchemalessExampleTest.testStreamingRequest
Error Message:
Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "cat"
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "cat"
at __randomizedtesting.SeedInfo.seed([20658798CDB011CF:E78CFF96EFBBBA9C]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
at org.apache.solr.client.solrj.SolrExampleTestsBase.testStreamingRequest(SolrExampleTestsBase.java:230)
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.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:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.client.solrj.SolrSchemalessExampleTest.testCommitWithinOnDelete
Error Message:
Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "price"
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "price"
at __randomizedtesting.SeedInfo.seed([20658798CDB011CF:4C7170291733FBF4]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:117)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:103)
at org.apache.solr.client.solrj.SolrExampleTestsBase.testCommitWithinOnDelete(SolrExampleTestsBase.java:134)
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.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:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.client.solrj.SolrSchemalessExampleTest.testCommitWithinOnAdd
Error Message:
Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "price"
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "price"
at __randomizedtesting.SeedInfo.seed([20658798CDB011CF:3CEAADF4BC12F1C4]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
at org.apache.solr.client.solrj.SolrExampleTestsBase.testCommitWithinOnAdd(SolrExampleTestsBase.java:63)
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.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:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10659 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrSchemalessExampleTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/init-core-data-001
[junit4] 2> 3692 T23 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 3781 T23 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 3781 T23 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 3910 T23 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3988 T23 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51537
[junit4] 2> 4019 T23 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@1b271f1
[junit4] 2> 4023 T23 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4023 T23 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001
[junit4] 2> 4024 T23 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/'
[junit4] 2> 4043 T23 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/solr.xml
[junit4] 2> 4083 T23 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001
[junit4] 2> 4083 T23 oasc.CoreContainer.<init> New CoreContainer 19140156
[junit4] 2> 4084 T23 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/]
[junit4] 2> 4096 T23 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 4097 T23 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 4097 T23 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 4097 T23 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4097 T23 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 4098 T23 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4098 T23 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4098 T23 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4098 T23 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4099 T23 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4099 T23 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 4100 T23 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4100 T23 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4100 T23 oasc.CoreContainer.load Host Name:
[junit4] 2> 4102 T23 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001
[junit4] 2> 4103 T23 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/
[junit4] 2> 4104 T23 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 4104 T35 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/'
[junit4] 2> 4148 T35 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 4149 T35 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 4150 T35 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-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../contrib/extraction/lib).
[junit4] 2> 4151 T35 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 4151 T35 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-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../contrib/clustering/lib).
[junit4] 2> 4151 T35 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 4152 T35 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-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../contrib/langid/lib).
[junit4] 2> 4152 T35 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 4152 T35 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-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../contrib/velocity/lib).
[junit4] 2> 4156 T35 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 4188 T35 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 4261 T35 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4266 T35 oass.ManagedIndexSchemaFactory.readSchemaLocally The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 4270 T35 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf/schema.xml
[junit4] 2> 4309 T35 oass.IndexSchema.readSchema [collection1] Schema name=example-schemaless
[junit4] 2> 5061 T35 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 5097 T35 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 5103 T35 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 5187 T35 oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 5189 T35 oass.ManagedIndexSchemaFactory.upgradeToManagedSchema After upgrading to managed schema, renamed the non-managed schema /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf/schema.xml to /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf/schema.xml.bak
[junit4] 2> 5190 T35 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/
[junit4] 2> 5190 T35 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 5191 T35 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002/
[junit4] 2> 5194 T35 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 5205 T35 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 5205 T35 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 5212 T35 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002
[junit4] 2> 5212 T35 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002/index/
[junit4] 2> 5213 T35 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002/index' doesn't exist. Creating new index...
[junit4] 2> 5213 T35 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002/index
[junit4] 2> 5214 T35 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_1,generation=1}
[junit4] 2> 5214 T35 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 5220 T35 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 5220 T35 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 5221 T35 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 5228 T35 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 5228 T35 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 5294 T35 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 5445 T35 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 5446 T35 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5446 T35 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5446 T35 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5447 T35 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5447 T35 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 5447 T35 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 5448 T35 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 5448 T35 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 5448 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 5450 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 5450 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 5451 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 5451 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 5451 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 5452 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 5452 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 5452 T35 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 5461 T35 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 5462 T35 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4] 2> 5462 T35 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4] 2> 5462 T35 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 5463 T35 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 5463 T35 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 5464 T35 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 5464 T35 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 5464 T35 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 5468 T35 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 5468 T35 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 5468 T35 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4] 2> 5468 T35 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 5468 T35 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4] 2> 5469 T35 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 5469 T35 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4] 2> 5469 T35 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 5469 T35 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4] 2> 5471 T35 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5474 T35 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5475 T35 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5476 T35 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5479 T35 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 5480 T35 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 5480 T35 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 5481 T35 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_1,generation=1}
[junit4] 2> 5481 T35 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 5481 T35 oass.SolrIndexSearcher.<init> Opening Searcher@c41a0b[collection1] main
[junit4] 2> 5483 T35 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf
[junit4] 2> 5483 T35 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf}
[junit4] 2> 5484 T35 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf
[junit4] 2> 5484 T35 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 5484 T35 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 5503 T35 oass.DirectSolrSpellChecker.init init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 5521 T35 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4] 2> 5525 T35 oashc.QueryElevationComponent.inform Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf/elevate.xml
[junit4] 2> 5553 T35 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 5553 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@c41a0b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 5554 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 5554 T35 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 5554 T36 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 5555 T23 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
[junit4] 2> 5556 T23 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 5555 T36 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 5556 T23 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#51537
[junit4] 2> 5556 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c41a0b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 5565 T23 oas.SolrTestCaseJ4.setUp ###Starting testStreamingRequest
[junit4] 2> ASYNC NEW_CORE C18 name=collection1 org.apache.solr.core.SolrCore@16bb5c8
[junit4] 2> 5891 T32 C18 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1489652167133364224)} 0 5
[junit4] 2> ASYNC NEW_CORE C19 name=collection1 org.apache.solr.core.SolrCore@16bb5c8
[junit4] 2> 5932 T34 C19 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 5932 T34 C19 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 5933 T34 C19 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 5933 T34 C19 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 5934 T34 C19 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 3
[junit4] 2> 5941 T31 C19 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=3
[junit4] 2> 5946 T33 C19 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {} 0 2
[junit4] 2> 5946 T33 C19 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: undefined field: "cat"
[junit4] 2> at org.apache.solr.schema.IndexSchema.getField(IndexSchema.java:1222)
[junit4] 2> at org.apache.solr.schema.IndexSchema.getCopyFieldsList(IndexSchema.java:1339)
[junit4] 2> at org.apache.solr.update.DocumentBuilder.toDocument(DocumentBuilder.java:110)
[junit4] 2> at org.apache.solr.update.AddUpdateCommand.getLuceneDocument(AddUpdateCommand.java:78)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:240)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:166)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:69)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:51)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:931)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1085)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:697)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:247)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:174)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:103)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:144)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2005)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:779)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:414)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:201)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 5949 T23 oas.SolrTestCaseJ4.tearDown ###Ending testStreamingRequest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrSchemalessExampleTest -Dtests.method=testStreamingRequest -Dtests.seed=20658798CDB011CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el_CY -Dtests.timezone=Europe/Samara -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.51s J0 | SolrSchemalessExampleTest.testStreamingRequest <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "cat"
[junit4] > at __randomizedtesting.SeedInfo.seed([20658798CDB011CF:E78CFF96EFBBBA9C]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
[junit4] > at org.apache.solr.client.solrj.SolrExampleTestsBase.testStreamingRequest(SolrExampleTestsBase.java:230)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 6069 T23 oas.SolrTestCaseJ4.setUp ###Starting testArbitraryJsonIndexing
[junit4] 2> ASYNC NEW_CORE C20 name=collection1 org.apache.solr.core.SolrCore@16bb5c8
[junit4] 2> 6072 T34 C20 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1489652167324205056)} 0 1
[junit4] 2> 6075 T31 C20 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6076 T31 C20 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 6076 T31 C20 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 6077 T31 C20 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6077 T31 C20 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 2
[junit4] 2> 6080 T33 C20 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 6172 T32 C20 oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 6183 T32 C20 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update/json/docs params={} {add=[abc1 (1489652167350419456), 09da3573-f57f-4d79-a1e1-e901b06f36f6 (1489652167440596992)]} 0 100
[junit4] 2> 6190 T31 C20 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C21 name=collection1 org.apache.solr.core.SolrCore@16bb5c8
[junit4] 2> 6210 T31 C21 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_2,generation=2}
[junit4] 2> 6211 T31 C21 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 6217 T31 C21 oass.SolrIndexSearcher.<init> Opening Searcher@879e96[collection1] main
[junit4] 2> 6218 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@879e96[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C2)))}
[junit4] 2> 6218 T31 C21 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6219 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 6219 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@879e96[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C2)))}
[junit4] 2> 6220 T31 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 30
[junit4] 2> 6223 T33 C21 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=2 status=0 QTime=0
[junit4] 2> 6228 T23 oas.SolrTestCaseJ4.tearDown ###Ending testArbitraryJsonIndexing
[junit4] 2> 6238 T23 oas.SolrTestCaseJ4.setUp ###Starting testAddDelete
[junit4] 2> 6311 T32 C21 oass.SolrIndexSearcher.<init> Opening Searcher@9ff595[collection1] realtime
[junit4] 2> 6311 T32 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1489652167502462976)} 0 70
[junit4] 2> 6319 T34 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1489652167581106176)]} 0 4
[junit4] 2> 6322 T31 C21 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6326 T31 C21 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_3,generation=3}
[junit4] 2> 6327 T31 C21 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 6329 T31 C21 oass.SolrIndexSearcher.<init> Opening Searcher@a588d[collection1] main
[junit4] 2> 6330 T31 C21 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6330 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@a588d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.0.0):C1)))}
[junit4] 2> 6331 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 6332 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a588d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.0.0):C1)))}
[junit4] 2> 6335 T31 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 13
[junit4] 2> 6339 T33 C21 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=0
[junit4] 2> 6345 T32 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[0 & 222 (-1489652167610466304)]} 0 1
[junit4] 2> 6350 T34 C21 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6352 T34 C21 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_4,generation=4}
[junit4] 2> 6353 T34 C21 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 6354 T34 C21 oass.SolrIndexSearcher.<init> Opening Searcher@ffc999[collection1] main
[junit4] 2> 6355 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@ffc999[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 6356 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 6355 T34 C21 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6356 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ffc999[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 6358 T34 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 8
[junit4] 2> 6362 T31 C21 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 6368 T33 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1489652167634583552)]} 0 2
[junit4] 2> 6373 T32 C21 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6379 T32 C21 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_5,generation=5}
[junit4] 2> 6380 T32 C21 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 6383 T32 C21 oass.SolrIndexSearcher.<init> Opening Searcher@11acf91[collection1] main
[junit4] 2> 6385 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@11acf91[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.0.0):C1)))}
[junit4] 2> 6385 T32 C21 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6385 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 6386 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11acf91[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.0.0):C1)))}
[junit4] 2> 6387 T32 C21 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 15
[junit4] 2> 6391 T34 C21 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=1
[junit4] 2> ASYNC NEW_CORE C22 name=collection1 org.apache.solr.core.SolrCore@16bb5c8
[junit4] 2> 6405 T31 C22 oass.SolrIndexSearcher.<init> Opening Searcher@1a3dbc6[collection1] realtime
[junit4] 2> 6406 T31 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=id:"0\ \&\ 222" (-1489652167672332288)} 0 3
[junit4] 2> 6410 T33 C22 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6410 T33 C22 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_6,generation=6}
[junit4] 2> 6411 T33 C22 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 6411 T33 C22 oass.SolrIndexSearcher.<init> Opening Searcher@9ea8[collection1] main
[junit4] 2> 6413 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@9ea8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 6413 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 6413 T33 C22 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6413 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9ea8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 6415 T33 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 6
[junit4] 2> 6419 T32 C22 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4] 2> 6423 T34 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1489652167692255232)]} 0 1
[junit4] 2> 6427 T31 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 & 222 (1489652167697498112)]} 0 0
[junit4] 2> 6431 T33 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2 & 222 (1489652167700643840)]} 0 1
[junit4] 2> 6434 T32 C22 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6437 T32 C22 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_7,generation=7}
[junit4] 2> 6438 T32 C22 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 6440 T32 C22 oass.SolrIndexSearcher.<init> Opening Searcher@2b177a[collection1] main
[junit4] 2> 6440 T32 C22 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6440 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2b177a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.0.0):C3)))}
[junit4] 2> 6441 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 6441 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2b177a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.0.0):C3)))}
[junit4] 2> 6442 T32 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 8
[junit4] 2> 6445 T31 C22 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=3 status=0 QTime=0
[junit4] 2> 6449 T33 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[0 & 222 (-1489652167719518208), 1 & 222 (-1489652167720566784), 2 & 222 (-1489652167720566785)]} 0 1
[junit4] 2> 6452 T32 C22 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6453 T32 C22 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2bc179 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ab1c28),segFN=segments_8,generation=8}
[junit4] 2> 6453 T32 C22 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 6454 T32 C22 oass.SolrIndexSearcher.<init> Opening Searcher@12ca65d[collection1] main
[junit4] 2> 6455 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12ca65d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 6455 T36 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 6455 T32 C22 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6455 T36 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12ca65d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 6456 T32 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 4
[junit4] 2> 6459 T34 C22 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 6460 T23 oas.SolrTestCaseJ4.tearDown ###Ending testAddDelete
[junit4] 2> 6469 T23 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnDelete
[junit4] 2> 6474 T31 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1489652167745732608)} 0 2
[junit4] 2> 6478 T33 C22 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6479 T33 C22 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 6480 T33 C22 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 6481 T33 C22 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6482 T33 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 4
[junit4] 2> 6486 T32 C22 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4] 2> 6496 T34 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 1
[junit4] 2> 6497 T34 C22 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: undefined field: "price"
[junit4] 2> at org.apache.solr.schema.IndexSchema.getField(IndexSchema.java:1222)
[junit4] 2> at org.apache.solr.schema.IndexSchema.getCopyFieldsList(IndexSchema.java:1339)
[junit4] 2> at org.apache.solr.update.DocumentBuilder.toDocument(DocumentBuilder.java:110)
[junit4] 2> at org.apache.solr.update.AddUpdateCommand.getLuceneDocument(AddUpdateCommand.java:78)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:240)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:166)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:69)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:51)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:931)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1085)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:697)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:247)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:174)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:103)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:144)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2005)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:779)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:414)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:201)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 6500 T23 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnDelete
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrSchemalessExampleTest -Dtests.method=testCommitWithinOnDelete -Dtests.seed=20658798CDB011CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el_CY -Dtests.timezone=Europe/Samara -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.05s J0 | SolrSchemalessExampleTest.testCommitWithinOnDelete <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "price"
[junit4] > at __randomizedtesting.SeedInfo.seed([20658798CDB011CF:4C7170291733FBF4]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
[junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:117)
[junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:103)
[junit4] > at org.apache.solr.client.solrj.SolrExampleTestsBase.testCommitWithinOnDelete(SolrExampleTestsBase.java:134)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 6515 T23 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnAdd
[junit4] 2> 6520 T31 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1489652167792918528)} 0 2
[junit4] 2> 6523 T33 C22 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6524 T33 C22 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 6524 T33 C22 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 6525 T33 C22 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 6525 T33 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&waitSearcher=true&commit=true&softCommit=false&wt=javabin} {commit=} 0 2
[junit4] 2> 6528 T32 C22 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 6531 T34 C22 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 0
[junit4] 2> 6532 T34 C22 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: undefined field: "price"
[junit4] 2> at org.apache.solr.schema.IndexSchema.getField(IndexSchema.java:1222)
[junit4] 2> at org.apache.solr.schema.IndexSchema.getCopyFieldsList(IndexSchema.java:1339)
[junit4] 2> at org.apache.solr.update.DocumentBuilder.toDocument(DocumentBuilder.java:110)
[junit4] 2> at org.apache.solr.update.AddUpdateCommand.getLuceneDocument(AddUpdateCommand.java:78)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:240)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:166)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:69)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:51)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:931)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1085)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:697)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:247)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:174)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:103)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:144)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2005)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:779)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:414)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:201)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 6534 T23 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnAdd
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrSchemalessExampleTest -Dtests.method=testCommitWithinOnAdd -Dtests.seed=20658798CDB011CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el_CY -Dtests.timezone=Europe/Samara -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.03s J0 | SolrSchemalessExampleTest.testCommitWithinOnAdd <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51537/solr/collection1: undefined field: "price"
[junit4] > at __randomizedtesting.SeedInfo.seed([20658798CDB011CF:3CEAADF4BC12F1C4]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
[junit4] > at org.apache.solr.client.solrj.SolrExampleTestsBase.testCommitWithinOnAdd(SolrExampleTestsBase.java:63)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 6566 T23 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=19140156
[junit4] 2> 6567 T23 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@16bb5c8
[junit4] 2> 6569 T23 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=11,autocommit maxTime=15000ms,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=1,deletesById=0,deletesByQuery=0,errors=3,cumulative_adds=10,cumulative_deletesById=4,cumulative_deletesByQuery=6,cumulative_errors=3,transaction_logs_total_size=1066,transaction_logs_total_number=10}
[junit4] 2> 6570 T23 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 6571 T23 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 6572 T23 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 6573 T23 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 6574 T23 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 6575 T23 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002/index;done=false>>]
[junit4] 2> 6576 T23 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002/index
[junit4] 2> 6576 T23 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002;done=false>>]
[junit4] 2> 6577 T23 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001/tempDir-002
[junit4] 2> 6579 T23 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 6758 T23 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest 20658798CDB011CF-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50), sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=el_CY, timezone=Europe/Samara
[junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.7.0_67 (32-bit)/cpus=8,threads=1,free=13088176,total=40067072
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrProperties, SolrSchemalessExampleTest]
[junit4] Completed on J0 in 3.12s, 5 tests, 3 errors <<< FAILURES!
[...truncated 159 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:203: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970: There were test failures: 52 suites, 329 tests, 3 errors
Total time: 79 minutes 31 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.7.0_67 -client -XX:+UseSerialGC (asserts: true)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.x-Linux (32bit/jdk1.7.0_67) - Build # 11698
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11698/
Java: 32bit/jdk1.7.0_67 -client -XX:+UseG1GC (asserts: false)
1 tests failed.
FAILED: org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.testDistribSearch
Error Message:
There are still nodes recoverying - waited for 30 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
at __randomizedtesting.SeedInfo.seed([6242DECD25D12107:E3A450D5528E413B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:178)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:835)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1454)
at org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.doTest(DistribDocExpirationUpdateProcessorTest.java:69)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor47.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$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.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:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10044 lines...]
[junit4] Suite: org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/init-core-data-001
[junit4] 2> 1599549 T7749 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 1599550 T7749 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /ig_w/a
[junit4] 2> 1599554 T7749 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 1599555 T7749 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1599555 T7750 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1599655 T7749 oasc.ZkTestServer.run start zk server on port:57690
[junit4] 2> 1599656 T7749 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1599657 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1599659 T7757 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fb3cf9 name:ZooKeeperConnection Watcher:127.0.0.1:57690 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1599659 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1599660 T7749 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1599660 T7749 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1599664 T7749 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1599665 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1599666 T7760 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cfe67f name:ZooKeeperConnection Watcher:127.0.0.1:57690/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1599666 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1599666 T7749 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1599666 T7749 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1599669 T7749 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1599671 T7749 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1599673 T7749 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1599674 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-doc-expire-update-processor.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1599674 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1599676 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 1599677 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1599679 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1599679 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1599681 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1599681 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1599683 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1599683 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1599685 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1599685 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1599687 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1599687 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1599689 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1599689 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1599691 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1599691 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1599693 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1599693 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1599695 T7749 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1599695 T7749 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1599697 T7749 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1599698 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1599699 T7763 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@818f2c name:ZooKeeperConnection Watcher:127.0.0.1:57690/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1599700 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1599700 T7749 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1599985 T7749 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1599988 T7749 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1600029 T7749 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:50645
[junit4] 2> 1600030 T7749 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ffb18
[junit4] 2> 1600031 T7749 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1600031 T7749 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-002
[junit4] 2> 1600031 T7749 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-002/'
[junit4] 2> 1600045 T7749 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-002/solr.xml
[junit4] 2> 1600075 T7749 oasc.CoreContainer.<init> New CoreContainer 5098998
[junit4] 2> 1600075 T7749 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-002/]
[junit4] 2> 1600076 T7749 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1600076 T7749 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1600077 T7749 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1600077 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1600077 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1600078 T7749 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1600078 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1600078 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1600079 T7749 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1600079 T7749 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1600080 T7749 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1600081 T7749 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1600081 T7749 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1600082 T7749 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1600082 T7749 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57690/solr
[junit4] 2> 1600083 T7749 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1600083 T7749 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1600084 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1600085 T7775 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19b640e name:ZooKeeperConnection Watcher:127.0.0.1:57690 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1600086 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1600087 T7749 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1600090 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1600092 T7778 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@483e9d name:ZooKeeperConnection Watcher:127.0.0.1:57690/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1600092 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1600095 T7749 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1600100 T7749 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1600104 T7749 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1600107 T7749 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1600110 T7749 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1600114 T7749 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1600115 T7749 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50645_ig_w%2Fa
[junit4] 2> 1600116 T7749 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50645_ig_w%2Fa
[junit4] 2> 1600119 T7749 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1600120 T7749 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1600122 T7749 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1600124 T7749 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:50645_ig_w%2Fa
[junit4] 2> 1600124 T7749 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1600126 T7749 oasc.Overseer.start Overseer (id=93104671688753156-127.0.0.1:50645_ig_w%2Fa-n_0000000000) starting
[junit4] 2> 1600129 T7749 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1600136 T7749 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1600137 T7780 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1600138 T7749 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1600140 T7749 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1600141 T7749 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1600143 T7779 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1600146 T7782 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 1600147 T7782 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1600147 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1600147 T7782 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1600150 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = {
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"https://127.0.0.1:50645/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "node_name":"127.0.0.1:50645_ig_w%2Fa",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down"} current state version: 0
[junit4] 2> 1600151 T7779 oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"https://127.0.0.1:50645/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "node_name":"127.0.0.1:50645_ig_w%2Fa",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down"}
[junit4] 2> 1600152 T7779 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 1600152 T7779 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1600154 T7778 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> 1601148 T7782 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1601149 T7782 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 1601150 T7782 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1601150 T7782 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1601152 T7782 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1601152 T7782 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-002/collection1/'
[junit4] 2> 1601154 T7782 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 1601154 T7782 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 1601155 T7782 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 1601173 T7782 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1601176 T7782 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1601183 T7782 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1601198 T7782 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 1601228 T7782 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1601230 T7782 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1601238 T7782 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1601472 T7782 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1601474 T7782 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1601475 T7782 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1601479 T7782 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1601496 T7782 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1601496 T7782 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1601497 T7782 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-002/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/control/data/
[junit4] 2> 1601497 T7782 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1601497 T7782 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/control/data
[junit4] 2> 1601498 T7782 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/control/data/index/
[junit4] 2> 1601498 T7782 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 1601498 T7782 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/control/data/index
[junit4] 2> 1601499 T7782 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=9.3173828125, floorSegmentMB=1.87109375, forceMergeDeletesPctAllowed=2.4461517832665725, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1601500 T7782 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e190d4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1eb5a8b),segFN=segments_1,generation=1}
[junit4] 2> 1601501 T7782 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1601502 T7782 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-defaults"
[junit4] 2> 1601503 T7782 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field"
[junit4] 2> 1601504 T7782 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-param"
[junit4] 2> 1601504 T7782 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
[junit4] 2> 1601505 T7782 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 1601505 T7782 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 1601506 T7782 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1601507 T7782 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1601507 T7782 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1601508 T7782 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1601508 T7782 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1601509 T7782 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1601509 T7782 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1601510 T7782 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1601510 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1601511 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1601512 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1601512 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1601513 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1601513 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1601514 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1601514 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 1601515 T7782 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1601515 T7782 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 1601517 T7782 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1601518 T7782 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1601519 T7782 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1601520 T7782 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1601522 T7782 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1601522 T7782 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1601523 T7782 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1601523 T7782 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2114109047, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1601523 T7782 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e190d4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1eb5a8b),segFN=segments_1,generation=1}
[junit4] 2> 1601524 T7782 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1601524 T7782 oass.SolrIndexSearcher.<init> Opening Searcher@1e92443[collection1] main
[junit4] 2> 1601524 T7782 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1601525 T7782 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1601525 T7782 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1601526 T7782 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1601526 T7782 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1601526 T7782 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1601527 T7782 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1601527 T7782 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1601528 T7782 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1601528 T7782 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1601528 T7783 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e92443[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1601528 T7782 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1601529 T7787 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:50645/ig_w/a collection:control_collection shard:shard1
[junit4] 2> 1601529 T7749 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1601530 T7749 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1601530 T7787 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1601533 T7749 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1601533 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1601535 T7787 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1601535 T7790 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19c931d name:ZooKeeperConnection Watcher:127.0.0.1:57690/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1601536 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1601536 T7749 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1601536 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1601537 T7787 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1601538 T7749 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1601538 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 1601538 T7787 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2264 name=collection1 org.apache.solr.core.SolrCore@1ecc959 url=https://127.0.0.1:50645/ig_w/a/collection1 node=127.0.0.1:50645_ig_w%2Fa C2264_STATE=coll:control_collection core:collection1 props:{base_url=https://127.0.0.1:50645/ig_w/a, core=collection1, node_name=127.0.0.1:50645_ig_w%2Fa, state=down}
[junit4] 2> 1601539 T7787 C2264 P50645 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:50645/ig_w/a/collection1/
[junit4] 2> 1601539 T7787 C2264 P50645 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1601539 T7787 C2264 P50645 oasc.SyncStrategy.syncToMe https://127.0.0.1:50645/ig_w/a/collection1/ has no replicas
[junit4] 2> 1601540 T7787 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:50645/ig_w/a/collection1/ shard1
[junit4] 2> 1601540 T7787 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1601540 T7749 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1601542 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1601543 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:50645/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1601645 T7778 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> 1601645 T7790 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> 1601693 T7787 oasc.ZkController.register We are https://127.0.0.1:50645/ig_w/a/collection1/ and leader is https://127.0.0.1:50645/ig_w/a/collection1/
[junit4] 2> 1601693 T7787 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:50645/ig_w/a
[junit4] 2> 1601693 T7787 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1601694 T7787 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 1601694 T7787 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1601695 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1601697 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"https://127.0.0.1:50645/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "node_name":"127.0.0.1:50645_ig_w%2Fa",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 1601698 T7779 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"https://127.0.0.1:50645/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "node_name":"127.0.0.1:50645_ig_w%2Fa",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active"}
[junit4] 2> 1601800 T7790 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> 1601800 T7778 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> 1601804 T7749 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 1601804 T7749 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1601807 T7749 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1601808 T7749 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:44610
[junit4] 2> 1601810 T7749 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ffb18
[junit4] 2> 1601810 T7749 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1601811 T7749 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-003
[junit4] 2> 1601811 T7749 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-003/'
[junit4] 2> 1601826 T7749 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-003/solr.xml
[junit4] 2> 1601856 T7749 oasc.CoreContainer.<init> New CoreContainer 13692216
[junit4] 2> 1601857 T7749 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-003/]
[junit4] 2> 1601858 T7749 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1601858 T7749 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1601858 T7749 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1601859 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1601859 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1601859 T7749 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1601860 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1601860 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1601860 T7749 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1601861 T7749 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1601862 T7749 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1601863 T7749 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1601863 T7749 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1601863 T7749 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1601864 T7749 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57690/solr
[junit4] 2> 1601864 T7749 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1601864 T7749 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1601865 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1601867 T7802 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ea61a5 name:ZooKeeperConnection Watcher:127.0.0.1:57690 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1601868 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1601868 T7749 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1601871 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1601872 T7805 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e1a879 name:ZooKeeperConnection Watcher:127.0.0.1:57690/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1601873 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1601880 T7749 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1602885 T7749 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44610_ig_w%2Fa
[junit4] 2> 1602887 T7749 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44610_ig_w%2Fa
[junit4] 2> 1602894 T7749 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1602903 T7806 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1602904 T7806 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1602905 T7806 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1602905 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1602908 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"https://127.0.0.1:44610/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:44610_ig_w%2Fa",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down"} current state version: 3
[junit4] 2> 1602910 T7779 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"https://127.0.0.1:44610/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:44610_ig_w%2Fa",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down"}
[junit4] 2> 1602911 T7779 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 1602911 T7779 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1603017 T7778 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> 1603017 T7790 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> 1603017 T7805 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> 1603906 T7806 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1603907 T7806 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1603908 T7806 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1603908 T7806 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1603910 T7806 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1603910 T7806 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-003/collection1/'
[junit4] 2> 1603912 T7806 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 1603912 T7806 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 1603913 T7806 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 1603930 T7806 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1603934 T7806 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1603941 T7806 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1603956 T7806 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 1603986 T7806 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1603988 T7806 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1603995 T7806 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1604231 T7806 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1604233 T7806 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1604234 T7806 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1604238 T7806 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1604268 T7806 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1604268 T7806 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1604269 T7806 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-003/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty1/
[junit4] 2> 1604269 T7806 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1604270 T7806 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty1
[junit4] 2> 1604270 T7806 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty1/index/
[junit4] 2> 1604271 T7806 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 1604271 T7806 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty1/index
[junit4] 2> 1604272 T7806 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=9.3173828125, floorSegmentMB=1.87109375, forceMergeDeletesPctAllowed=2.4461517832665725, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1604273 T7806 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@33375 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2785ee),segFN=segments_1,generation=1}
[junit4] 2> 1604274 T7806 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1604275 T7806 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-defaults"
[junit4] 2> 1604275 T7806 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field"
[junit4] 2> 1604276 T7806 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-param"
[junit4] 2> 1604276 T7806 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
[junit4] 2> 1604276 T7806 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 1604277 T7806 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 1604277 T7806 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1604277 T7806 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1604278 T7806 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1604278 T7806 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1604278 T7806 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1604278 T7806 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1604279 T7806 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1604279 T7806 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1604279 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1604280 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1604280 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1604280 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1604280 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1604281 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1604281 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1604281 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 1604281 T7806 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1604282 T7806 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 1604284 T7806 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1604285 T7806 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1604286 T7806 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1604287 T7806 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1604288 T7806 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1604289 T7806 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1604289 T7806 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1604290 T7806 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2114109047, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1604290 T7806 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@33375 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2785ee),segFN=segments_1,generation=1}
[junit4] 2> 1604290 T7806 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1604291 T7806 oass.SolrIndexSearcher.<init> Opening Searcher@198f498[collection1] main
[junit4] 2> 1604291 T7806 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1604292 T7806 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1604293 T7806 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1604293 T7806 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1604293 T7806 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1604293 T7806 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1604294 T7806 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1604295 T7806 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1604295 T7806 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1604295 T7806 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1604296 T7807 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@198f498[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1604296 T7806 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1604297 T7811 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:44610/ig_w/a collection:collection1 shard:shard2
[junit4] 2> 1604298 T7749 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1604298 T7749 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1604299 T7811 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 1604306 T7811 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 1604309 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1604309 T7811 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1604310 T7811 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1604310 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> ASYNC NEW_CORE C2265 name=collection1 org.apache.solr.core.SolrCore@1873af9 url=https://127.0.0.1:44610/ig_w/a/collection1 node=127.0.0.1:44610_ig_w%2Fa C2265_STATE=coll:collection1 core:collection1 props:{base_url=https://127.0.0.1:44610/ig_w/a, core=collection1, node_name=127.0.0.1:44610_ig_w%2Fa, state=down}
[junit4] 2> 1604310 T7811 C2265 P44610 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:44610/ig_w/a/collection1/
[junit4] 2> 1604311 T7811 C2265 P44610 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1604312 T7811 C2265 P44610 oasc.SyncStrategy.syncToMe https://127.0.0.1:44610/ig_w/a/collection1/ has no replicas
[junit4] 2> 1604313 T7811 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:44610/ig_w/a/collection1/ shard2
[junit4] 2> 1604313 T7811 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 1604317 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1604317 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:44610/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1604420 T7805 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> 1604420 T7790 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> 1604420 T7778 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> 1604468 T7811 oasc.ZkController.register We are https://127.0.0.1:44610/ig_w/a/collection1/ and leader is https://127.0.0.1:44610/ig_w/a/collection1/
[junit4] 2> 1604468 T7811 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:44610/ig_w/a
[junit4] 2> 1604468 T7811 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1604468 T7811 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 1604468 T7811 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1604470 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1604471 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "shard":"shard2",
[junit4] 2> "base_url":"https://127.0.0.1:44610/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:44610_ig_w%2Fa",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 1604472 T7779 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "shard":"shard2",
[junit4] 2> "base_url":"https://127.0.0.1:44610/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:44610_ig_w%2Fa",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active"}
[junit4] 2> ASYNC NEW_CORE C2266 name=collection1 org.apache.solr.core.SolrCore@1ecc959 url=https://127.0.0.1:50645/ig_w/a/collection1 node=127.0.0.1:50645_ig_w%2Fa C2266_STATE=coll:control_collection core:collection1 props:{base_url=https://127.0.0.1:50645/ig_w/a, core=collection1, node_name=127.0.0.1:50645_ig_w%2Fa, state=active, leader=true}
[junit4] 2> 1604528 T7784 C2266 P50645 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Begining periodic deletion of expired docs
[junit4] 2> 1604530 T7784 C2266 P50645 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 1604530 T7784 C2266 P50645 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 1604530 T7784 C2266 P50645 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1604530 T7784 C2266 P50645 oasup.LogUpdateProcessor.finish [collection1] {deleteByQuery={!cache=false}eXpField_tdt:[* TO 2015-01-07T21:02:31.003Z] (-1489674752233570304),commit=} 0 2
[junit4] 2> 1604531 T7784 C2266 P50645 oasup.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run Finished periodic deletion of expired docs
[junit4] 2> 1604562 T7749 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 1604563 T7749 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1604566 T7749 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1604567 T7749 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:51747
[junit4] 2> 1604569 T7749 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ffb18
[junit4] 2> 1604570 T7749 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1604570 T7749 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-004
[junit4] 2> 1604570 T7749 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-004/'
[junit4] 2> 1604574 T7778 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> 1604574 T7805 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> 1604574 T7790 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> 1604586 T7749 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-004/solr.xml
[junit4] 2> 1604616 T7749 oasc.CoreContainer.<init> New CoreContainer 14012015
[junit4] 2> 1604617 T7749 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-004/]
[junit4] 2> 1604618 T7749 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1604618 T7749 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1604618 T7749 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1604619 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1604619 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1604619 T7749 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1604620 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1604620 T7749 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1604620 T7749 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1604620 T7749 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1604622 T7749 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1604623 T7749 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1604623 T7749 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1604623 T7749 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1604624 T7749 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57690/solr
[junit4] 2> 1604624 T7749 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1604625 T7749 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1604625 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1604627 T7823 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1eb4a90 name:ZooKeeperConnection Watcher:127.0.0.1:57690 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1604627 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1604627 T7749 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1604631 T7749 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1604633 T7826 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18cde53 name:ZooKeeperConnection Watcher:127.0.0.1:57690/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1604633 T7749 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1604644 T7749 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1605650 T7749 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51747_ig_w%2Fa
[junit4] 2> 1605652 T7749 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51747_ig_w%2Fa
[junit4] 2> 1605660 T7749 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1605671 T7827 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1605671 T7827 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1605673 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1605673 T7827 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1605675 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"https://127.0.0.1:51747/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:51747_ig_w%2Fa",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down"} current state version: 6
[junit4] 2> 1605677 T7779 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"https://127.0.0.1:51747/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:51747_ig_w%2Fa",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down"}
[junit4] 2> 1605677 T7779 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 1605678 T7779 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1605784 T7826 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> 1605784 T7778 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> 1605784 T7805 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> 1605784 T7790 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> 1606674 T7827 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1606675 T7827 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1606676 T7827 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1606676 T7827 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1606678 T7827 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1606678 T7827 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-004/collection1/'
[junit4] 2> 1606680 T7827 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 1606680 T7827 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 1606681 T7827 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest%206242DECD25D12107-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 1606702 T7827 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1606705 T7827 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1606712 T7827 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1606729 T7827 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 1606757 T7827 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1606759 T7827 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1606765 T7827 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1607008 T7827 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1607010 T7827 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1607012 T7827 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1607015 T7827 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1607033 T7827 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1607033 T7827 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1607033 T7827 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-004/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty2/
[junit4] 2> 1607034 T7827 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1607034 T7827 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty2
[junit4] 2> 1607034 T7827 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty2/index/
[junit4] 2> 1607035 T7827 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 1607035 T7827 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001/tempDir-001/jetty2/index
[junit4] 2> 1607036 T7827 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=9.3173828125, floorSegmentMB=1.87109375, forceMergeDeletesPctAllowed=2.4461517832665725, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1607037 T7827 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1546860 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@907c6f),segFN=segments_1,generation=1}
[junit4] 2> 1607037 T7827 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1607039 T7827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-defaults"
[junit4] 2> 1607040 T7827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field"
[junit4] 2> 1607040 T7827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-param"
[junit4] 2> 1607041 T7827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
[junit4] 2> 1607041 T7827 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 1607042 T7827 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
[junit4] 2> 1607043 T7827 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1607043 T7827 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1607043 T7827 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1607044 T7827 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1607044 T7827 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1607045 T7827 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1607045 T7827 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1607046 T7827 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1607046 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1607047 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1607047 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1607048 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1607048 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1607048 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1607049 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1607049 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 1607050 T7827 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1607050 T7827 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 1607055 T7827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1607056 T7827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1607057 T7827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1607058 T7827 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1607059 T7827 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1607060 T7827 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1607060 T7827 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1607060 T7827 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2114109047, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1607061 T7827 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1546860 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@907c6f),segFN=segments_1,generation=1}
[junit4] 2> 1607061 T7827 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1607061 T7827 oass.SolrIndexSearcher.<init> Opening Searcher@fcbf6f[collection1] main
[junit4] 2> 1607061 T7827 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1607062 T7827 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1607062 T7827 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1607063 T7827 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1607063 T7827 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1607063 T7827 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1607064 T7827 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1607064 T7827 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1607064 T7827 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1607065 T7827 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1607065 T7828 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fcbf6f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1607065 T7827 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1607066 T7832 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:51747/ig_w/a collection:collection1 shard:shard1
[junit4] 2> 1607066 T7749 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1607067 T7749 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1607067 T7832 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 1607070 T7832 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1607072 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1607072 T7832 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1607072 T7832 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2267 name=collection1 org.apache.solr.core.SolrCore@1fdd3c9 url=https://127.0.0.1:51747/ig_w/a/collection1 node=127.0.0.1:51747_ig_w%2Fa C2267_STATE=coll:collection1 core:collection1 props:{base_url=https://127.0.0.1:51747/ig_w/a, core=collection1, node_name=127.0.0.1:51747_ig_w%2Fa, state=down}
[junit4] 2> 1607072 T7832 C2267 P51747 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:51747/ig_w/a/collection1/
[junit4] 2> 1607073 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 7
[junit4] 2> 1607073 T7832 C2267 P51747 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1607073 T7832 C2267 P51747 oasc.SyncStrategy.syncToMe https://127.0.0.1:51747/ig_w/a/collection1/ has no replicas
[junit4] 2> 1607073 T7832 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:51747/ig_w/a/collection1/ shard1
[junit4] 2> 1607074 T7832 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1607076 T7778 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1607077 T7779 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:51747/ig_w/a",
[junit4] 2> "core":"collection1",
[junit4
[...truncated too long message...]
or.java:220)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:469)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:226)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:162)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:124)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:357)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:267)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1653600 T7749 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/ig_w/a,null}
[junit4] 2> 1653790 T7749 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 1653792 T7749 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57690 57690
[junit4] 2> 1654468 T7750 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57690 57690
[junit4] 2> 1654470 T7750 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistribDocExpirationUpdateProcessorTest -Dtests.method=testDistribSearch -Dtests.seed=6242DECD25D12107 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh_TW -Dtests.timezone=Africa/Lome -Dtests.asserts=false -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 54.9s J1 | DistribDocExpirationUpdateProcessorTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 30 seconds
[junit4] > at __randomizedtesting.SeedInfo.seed([6242DECD25D12107:E3A450D5528E413B]:0)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:178)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:835)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1454)
[junit4] > at org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.doTest(DistribDocExpirationUpdateProcessorTest.java:69)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1654484 T7749 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest 6242DECD25D12107-001
[junit4] 2> 54939 T7748 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene50, sim=DefaultSimilarity, locale=zh_TW, timezone=Africa/Lome
[junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.7.0_67 (32-bit)/cpus=8,threads=1,free=325316000,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DocExpirationUpdateProcessorFactoryTest, LoggingHandlerTest, TestRTGBase, ReturnFieldsTest, SimplePostToolTest, SOLR749Test, TestSolr4Spatial, DebugComponentTest, FacetPivotSmallTest, IndexBasedSpellCheckerTest, BadComponentTest, CachingDirectoryFactoryTest, DocumentBuilderTest, TestTrie, OverseerCollectionProcessorTest, DeleteReplicaTest, TestSolrJ, SuggesterTSTTest, OverseerStatusTest, TestReplicationHandlerBackup, DirectUpdateHandlerOptimizeTest, ZkNodePropsTest, HdfsCollectionsAPIDistributedZkTest, CollectionsAPIDistributedZkTest, DistributedExpandComponentTest, TestCharFilters, ZkSolrClientTest, XsltUpdateRequestHandlerTest, ChaosMonkeySafeLeaderTest, SolrCmdDistributorTest, SolrXmlInZkTest, TestSolrConfigHandler, FastVectorHighlighterTest, HdfsSyncSliceTest, TestManagedStopFilterFactory, ConvertedLegacyTest, TestBM25SimilarityFactory, TestSolrXmlPersistor, TestFieldTypeResource, DirectSolrSpellCheckerTest, HdfsRecoveryZkTest, TestShardHandlerFactory, TestAnalyzeInfixSuggestions, TestDistributedGrouping, TestPseudoReturnFields, DocValuesMissingTest, SpatialRPTFieldTypeTest, TestPostingsSolrHighlighter, TestClusterStateMutator, TestDefaultStatsCache, AtomicUpdatesTest, CursorPagingTest, TestCoreDiscovery, FullSolrCloudDistribCmdsTest, StandardRequestHandlerTest, TestImplicitCoreProperties, TestRandomFaceting, HdfsWriteToMultipleCollectionsTest, SynonymTokenizerTest, SpellingQueryConverterTest, TestBulkSchemaAPI, HighlighterTest, TestPerFieldSimilarity, TestMaxScoreQueryParser, TestPivotHelperCode, TestCursorMarkWithoutUniqueKey, QueryEqualityTest, TestSolrIndexConfig, EchoParamsTest, DistributedFacetPivotSmallAdvancedTest, TestRangeQuery, TestDynamicLoading, TestDistribDocBasedVersion, LeaderElectionIntegrationTest, SpatialFilterTest, LeaderFailoverAfterPartitionTest, FileUtilsTest, DistribCursorPagingTest, TestFieldCollectionResource, SpellPossibilityIteratorTest, CurrencyFieldXmlFileTest, PreAnalyzedUpdateProcessorTest, MBeansHandlerTest, TestComplexPhraseQParserPlugin, TestDocBasedVersionConstraints, TestConfig, TestSolrDynamicMBean, HdfsChaosMonkeySafeLeaderTest, TestSolrQueryParser, ExternalFileFieldSortTest, BasicFunctionalityTest, TestReload, TermVectorComponentTest, TermsComponentTest, TermVectorComponentDistributedTest, TestRawResponseWriter, PolyFieldTest, TestCloudSchemaless, HdfsLockFactoryTest, TestFoldingMultitermQuery, SignatureUpdateProcessorFactoryTest, TestBulkSchemaConcurrent, TestLRUStatsCache, TestNoOpRegenerator, EnumFieldTest, TestSuggestSpellingConverter, TestRecovery, RAMDirectoryFactoryTest, UUIDUpdateProcessorFallbackTest, ChaosMonkeyNothingIsSafeTest, TestOverriddenPrefixQueryForCustomFieldType, TestRealTimeGet, BasicDistributedZkTest, BasicDistributedZk2Test, SyncSliceTest, TestReplicationHandler, TestZkChroot, TestRandomDVFaceting, ZkControllerTest, TestJoin, TestCoreContainer, SpellCheckComponentTest, TestGroupingSearch, TestFunctionQuery, DirectUpdateHandlerTest, TestBadConfig, TestIndexSearcher, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, CoreAdminHandlerTest, DocValuesTest, TestStressLucene, SuggesterWFSTTest, SchemaVersionSpecificBehaviorTest, TestUpdate, FieldMutatingUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, TestSolrDeletionPolicy1, CacheHeaderTest, LukeRequestHandlerTest, TestReversedWildcardFilterFactory, FileBasedSpellCheckerTest, PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, RegexBoostProcessorTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestBinaryResponseWriter, TestQuerySenderListener, AlternateDirectoryTest, ResponseLogComponentTest, TestSolrDeletionPolicy2, SampleTest, TestSearchPerf, TestElisionMultitermQuery, DirectSolrConnectionTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestDefaultSimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, URLClassifyProcessorTest, PreAnalyzedFieldTest, DOMUtilTest, ClusterStateTest, TestUtils, SliceStateTest, UUIDFieldTest, CircularListTest, DistributedIntervalFacetingTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DistribDocExpirationUpdateProcessorTest]
[junit4] Completed on J1 in 55.22s, 1 test, 1 failure <<< FAILURES!
[...truncated 283 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970: There were test failures: 461 suites, 1869 tests, 1 failure, 47 ignored (22 assumptions)
Total time: 75 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.7.0_67 -client -XX:+UseG1GC (asserts: false)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.x-Linux (32bit/jdk1.8.0_20) - Build # 11697
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11697/
Java: 32bit/jdk1.8.0_20 -client -XX:+UseConcMarkSweepGC (asserts: false)
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestBadConfig
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([3D72423A673AD680]:0)
FAILED: org.apache.solr.core.TestBadConfig.testMissingScriptFile
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([3D72423A673AD680]:0)
Build Log:
[...truncated 10121 lines...]
[junit4] Suite: org.apache.solr.core.TestBadConfig
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001
[junit4] 2> 9837 T42 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 9841 T42 oas.SolrTestCaseJ4.setUp ###Starting testMultipleIndexConfigs
[junit4] 2> 9842 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 9842 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 9843 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 9843 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 9844 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 9882 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 9884 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 9885 T42 oas.SolrTestCaseJ4.tearDown ###Ending testMultipleIndexConfigs
[junit4] 2> 9889 T42 oas.SolrTestCaseJ4.setUp ###Starting testUnsetSysProperty
[junit4] 2> 9890 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 9891 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 9891 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 9892 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 9892 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 9915 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 9915 T42 oas.SolrTestCaseJ4.tearDown ###Ending testUnsetSysProperty
[junit4] 2> 9921 T42 oas.SolrTestCaseJ4.setUp ###Starting testMultipleCFS
[junit4] 2> 9921 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 9922 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 9922 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 9923 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 9923 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 9943 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 9946 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 9947 T42 oas.SolrTestCaseJ4.tearDown ###Ending testMultipleCFS
[junit4] 2> 9953 T42 oas.SolrTestCaseJ4.setUp ###Starting testMissingScriptFile
[junit4] 2> 10908 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 10909 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 10910 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 10913 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 10914 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 10955 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 10972 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 10993 T42 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-missing-scriptfile.xml
[junit4] 2> 10994 T42 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 11026 T42 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 11303 T42 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 11314 T42 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 11317 T42 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 11337 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11341 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11343 T42 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 11344 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 11346 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 11346 T42 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 11347 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 11347 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 11347 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 11348 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 11348 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 11419 T42 oasc.CoreContainer.<init> New CoreContainer 9595753
[junit4] 2> 11420 T42 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 11421 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 11421 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 11422 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 11422 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 11423 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 11423 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 11423 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 11424 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 11424 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 11424 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 11427 T42 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 11429 T42 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 11429 T42 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 11429 T42 oasc.CoreContainer.load Host Name:
[junit4] 2> 11433 T43 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 11434 T43 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 11434 T43 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 11434 T43 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 11459 T43 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 11467 T43 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 11483 T43 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-missing-scriptfile.xml
[junit4] 2> 11484 T43 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 11513 T43 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 11759 T43 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 11765 T43 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 11768 T43 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 11781 T43 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11784 T43 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11787 T43 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 11788 T43 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 11788 T43 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 11788 T43 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 11789 T43 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 11789 T43 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 11789 T43 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 11790 T43 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 11792 T43 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/
[junit4] 2> 11792 T43 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 11793 T43 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001
[junit4] 2> 11793 T43 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index/
[junit4] 2> 11794 T43 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 11794 T43 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index
[junit4] 2> 11863 T43 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ad46e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 11863 T43 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 11867 T43 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "force-script-engine" (default)
[junit4] 2> 11886 T43 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "force-script-engine" (default)
[junit4] 2> 11887 T43 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11887 T43 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11887 T43 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11888 T43 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11888 T43 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 11888 T43 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 11888 T43 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 11889 T43 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 11889 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 11890 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 11890 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 11890 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 11891 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 11891 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 11891 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 11892 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 11892 T43 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 11895 T43 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11896 T43 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11897 T43 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11898 T43 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11898 T43 oasc.RequestHandlers.initHandlersFromConfig WARN no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 11899 T43 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 11900 T43 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 11900 T43 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 11901 T43 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ad46e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 11902 T43 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 11909 T43 oass.SolrIndexSearcher.<init> Opening Searcher@1beb1bd[collection1] main
[junit4] 2> 11910 T43 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 11910 T43 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 11911 T43 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 11911 T43 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 11972 T43 oasup.StatelessScriptUpdateProcessorFactory.inform ERROR Unable to initialize scripts: Unable to evaluate script: a-file-name-that-does-not-exist.js Ignoring exception matching \Qa-file-name-that-does-not-exist.js\E
[junit4] 2> 11973 T43 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@141ec81
[junit4] 2> 11973 T44 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1beb1bd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 11973 T43 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 11973 T43 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 11974 T43 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 11974 T43 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 11976 T43 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 11977 T43 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 11977 T43 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001;done=false>>]
[junit4] 2> 11977 T43 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001
[junit4] 2> 11978 T43 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index;done=false>>]
[junit4] 2> 11978 T43 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index
[junit4] 2> 11980 T43 oasc.CoreContainer.create ERROR Error creating core [collection1]: Unable to initialize scripts: Unable to evaluate script: a-file-name-that-does-not-exist.js Ignoring exception matching \Qa-file-name-that-does-not-exist.js\E
[junit4] 2> 11982 T42 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 11983 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 11983 T42 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=9595753
[junit4] 2> 7199978 T41 ccr.ThreadLeakControl$2.evaluate WARNING Suite execution timed out: org.apache.solr.core.TestBadConfig
[junit4] 2> ==== jstack at approximately timeout time ====
[junit4] 2> "Thread-6" ID=45 WAITING on java.lang.Object@100d8f4
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on java.lang.Object@100d8f4
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:929)
[junit4] 2>
[junit4] 2> "TEST-TestBadConfig.testMissingScriptFile-seed#[3D72423A673AD680]" ID=42 WAITING on org.apache.solr.core.CloserThread@4eb280
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on org.apache.solr.core.CloserThread@4eb280
[junit4] 2> at java.lang.Thread.join(Thread.java:1245)
[junit4] 2> at java.lang.Thread.join(Thread.java:1319)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:357)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:337)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:641)
[junit4] 2> at org.apache.solr.core.AbstractBadConfigTestBase.assertConfigs(AbstractBadConfigTestBase.java:71)
[junit4] 2> at org.apache.solr.core.AbstractBadConfigTestBase.assertConfigs(AbstractBadConfigTestBase.java:36)
[junit4] 2> at org.apache.solr.core.TestBadConfig.testMissingScriptFile(TestBadConfig.java:64)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> "SUITE-TestBadConfig-seed#[3D72423A673AD680]" ID=41 RUNNABLE
[junit4] 2> at sun.management.ThreadImpl.dumpThreads0(Native Method)
[junit4] 2> at sun.management.ThreadImpl.dumpAllThreads(ThreadImpl.java:446)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:671)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$900(ThreadLeakControl.java:62)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:412)
[junit4] 2> - locked java.lang.Object@45b02c
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:642)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:129)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$1.run(RandomizedRunner.java:559)
[junit4] 2>
[junit4] 2> "JUnit4-serializer-daemon" ID=9 TIMED_WAITING
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.events.Serializer$1.run(Serializer.java:47)
[junit4] 2>
[junit4] 2> "Signal Dispatcher" ID=5 RUNNABLE
[junit4] 2>
[junit4] 2> "Finalizer" ID=3 WAITING on java.lang.ref.ReferenceQueue$Lock@e184eb
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on java.lang.ref.ReferenceQueue$Lock@e184eb
[junit4] 2> at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:142)
[junit4] 2> at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:158)
[junit4] 2> at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)
[junit4] 2>
[junit4] 2> "Reference Handler" ID=2 WAITING on java.lang.ref.Reference$Lock@12f27bb
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on java.lang.ref.Reference$Lock@12f27bb
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:157)
[junit4] 2>
[junit4] 2> "main" ID=1 WAITING on com.carrotsearch.randomizedtesting.RandomizedRunner$1@3dd2cf
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> - waiting on com.carrotsearch.randomizedtesting.RandomizedRunner$1@3dd2cf
[junit4] 2> at java.lang.Thread.join(Thread.java:1245)
[junit4] 2> at java.lang.Thread.join(Thread.java:1319)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:569)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:435)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:199)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:310)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:12)
[junit4] 2>
[junit4] 2> ^^==============================================
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBadConfig -Dtests.method=testMissingScriptFile -Dtests.seed=3D72423A673AD680 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_AU -Dtests.timezone=America/Chicago -Dtests.asserts=false -Dtests.file.encoding=UTF-8
[junit4] ERROR 7200s J1 | TestBadConfig.testMissingScriptFile <<<
[junit4] > Throwable #1: java.lang.Exception: Test abandoned because suite timeout was reached.
[junit4] > at __randomizedtesting.SeedInfo.seed([3D72423A673AD680]:0)
[junit4] 2> 7200006 T41 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> 7220091 T41 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 2 threads leaked from SUITE scope at org.apache.solr.core.TestBadConfig:
[junit4] 2> 1) Thread[id=42, name=TEST-TestBadConfig.testMissingScriptFile-seed#[3D72423A673AD680], state=WAITING, group=TGRP-TestBadConfig]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Thread.join(Thread.java:1245)
[junit4] 2> at java.lang.Thread.join(Thread.java:1319)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:357)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:337)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:641)
[junit4] 2> at org.apache.solr.core.AbstractBadConfigTestBase.assertConfigs(AbstractBadConfigTestBase.java:71)
[junit4] 2> at org.apache.solr.core.AbstractBadConfigTestBase.assertConfigs(AbstractBadConfigTestBase.java:36)
[junit4] 2> at org.apache.solr.core.TestBadConfig.testMissingScriptFile(TestBadConfig.java:64)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2) Thread[id=45, name=Thread-6, state=WAITING, group=TGRP-TestBadConfig]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:929)
[junit4] 2> 7220104 T41 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=42, name=TEST-TestBadConfig.testMissingScriptFile-seed#[3D72423A673AD680], state=WAITING, group=TGRP-TestBadConfig]
[junit4] 2> 2) Thread[id=45, name=Thread-6, state=WAITING, group=TGRP-TestBadConfig]
[junit4] 2> 7229942 T42 oas.SolrTestCaseJ4.tearDown ###Ending testMissingScriptFile
[junit4] 2> 7229949 T42 oas.SolrTestCaseJ4.setUp ###Starting testSchemaMutableButNotManaged
[junit4] 2> 7229950 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7229950 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7229951 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7229951 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7229952 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7229989 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7229995 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7230012 T42 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-schema-mutable-but-not-managed.xml
[junit4] 2> 7230017 T42 oass.ClassicIndexSchemaFactory.init ERROR Unexpected arg(s): {mutable=false,managedSchemaResourceName=schema.xml}
[junit4] 2> 7230018 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7230019 T42 oas.SolrTestCaseJ4.tearDown ###Ending testSchemaMutableButNotManaged
[junit4] 2> 7230026 T42 oas.SolrTestCaseJ4.setUp ###Starting testInvalidScriptFile
[junit4] 2> 7230073 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7230074 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7230075 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7230076 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7230076 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7230110 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7230125 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7230136 T42 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-invalid-scriptfile.xml
[junit4] 2> 7230136 T42 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 7230162 T42 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 7230358 T42 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 7230366 T42 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 7230368 T42 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 7230383 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7230386 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7230388 T42 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7230389 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7230389 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7230389 T42 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7230390 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7230390 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7230391 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 7230391 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 7230391 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 7230442 T42 oasc.CoreContainer.<init> New CoreContainer 24713389
[junit4] 2> 7230443 T42 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 7230444 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 7230444 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 7230444 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 7230445 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 7230445 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 7230445 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 7230445 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 7230446 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 7230446 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 7230446 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 7230448 T42 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 7230450 T42 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 7230450 T42 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 7230450 T42 oasc.CoreContainer.load Host Name:
[junit4] 2> 7230453 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7230453 T42 oas.SolrTestCaseJ4.tearDown ###Ending testInvalidScriptFile
[junit4] 2> 7230459 T42 oas.SolrTestCaseJ4.setUp ###Starting testMultipleDirectoryFactories
[junit4] 2> 7230460 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7230460 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7230461 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7230461 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7230462 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7230490 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7230497 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7230512 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7230513 T42 oas.SolrTestCaseJ4.tearDown ###Ending testMultipleDirectoryFactories
[junit4] 2> 7230518 T42 oas.SolrTestCaseJ4.setUp ###Starting testManagedSchemaCannotBeNamedSchemaDotXml
[junit4] 2> 7230518 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7230518 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7230519 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7230520 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7230520 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7230549 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7230554 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7230571 T42 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-managed-schema-named-schema.xml.xml
[junit4] 2> 7230578 T42 oass.ManagedIndexSchemaFactory.init ERROR managedSchemaResourceName can't be 'schema.xml'
[junit4] 2> 7230578 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7230579 T42 oas.SolrTestCaseJ4.tearDown ###Ending testManagedSchemaCannotBeNamedSchemaDotXml
[junit4] 2> 7230583 T42 oas.SolrTestCaseJ4.setUp ###Starting testUpdateLogButNoVersionField
[junit4] 2> 7230584 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7230584 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7230585 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7230585 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7230585 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7230621 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7230642 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7230705 T42 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 7230705 T42 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema12.xml
[junit4] 2> 7230713 T42 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 7230778 T3 oasc.CoreContainer.finalize ERROR CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=24713389
[junit4] 2> 7231075 T42 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 7231078 T42 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 7231080 T42 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 7231124 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7231130 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7231132 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 7231133 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 7231133 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 7231215 T42 oasc.CoreContainer.<init> New CoreContainer 27001632
[junit4] 2> 7231215 T42 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 7231216 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 7231216 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 7231217 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 7231217 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 7231217 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 7231218 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 7231218 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 7231218 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 7231219 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 7231219 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 7231223 T42 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 7231225 T42 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 7231226 T42 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 7231226 T42 oasc.CoreContainer.load Host Name:
[junit4] 2> 7231228 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7231229 T42 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateLogButNoVersionField
[junit4] 2> 7231236 T42 oas.SolrTestCaseJ4.setUp ###Starting testUnknownSchemaAttribute
[junit4] 2> 7231237 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7231238 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7231238 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7231239 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7231239 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7231277 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7231283 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7231303 T42 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-unexpected-schema-attribute.xml
[junit4] 2> 7231304 T42 oass.ManagedIndexSchemaFactory.init ERROR Unexpected arg(s): {bogusParam=bogusValue}
[junit4] 2> 7231304 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7231304 T42 oas.SolrTestCaseJ4.tearDown ###Ending testUnknownSchemaAttribute
[junit4] 2> 7231310 T42 oas.SolrTestCaseJ4.setUp ###Starting testBogusMergePolicy
[junit4] 2> 7231311 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7231312 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7231313 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7231313 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7231313 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7231337 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7231370 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7231382 T42 oasc.SolrConfig.<init> Loaded SolrConfig: bad-mp-solrconfig.xml
[junit4] 2> 7231383 T42 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-minimal.xml
[junit4] 2> 7231387 T42 oass.IndexSchema.readSchema [null] Schema name=minimal
[junit4] 2> 7231392 T42 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 7231393 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 7231393 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 7231394 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 7231470 T42 oasc.CoreContainer.<init> New CoreContainer 20451356
[junit4] 2> 7231471 T42 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 7231472 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 7231472 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 7231473 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 7231473 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 7231474 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 7231474 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 7231475 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 7231475 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 7231476 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 7231476 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 7231479 T42 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 7231482 T42 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 7231482 T42 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 7231483 T42 oasc.CoreContainer.load Host Name:
[junit4] 2> 7231490 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7231491 T42 oas.SolrTestCaseJ4.tearDown ###Ending testBogusMergePolicy
[junit4] 2> 7231499 T42 oas.SolrTestCaseJ4.setUp ###Starting testBogusScriptEngine
[junit4] 2> 7231506 T42 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 7231506 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7231508 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7231508 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7231509 T42 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7231538 T42 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7231549 T42 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7231566 T42 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-bogus-scriptengine-name.xml
[junit4] 2> 7231567 T42 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 7231585 T42 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 7231812 T42 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 7231820 T42 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 7231824 T42 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 7231836 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7231841 T42 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7231844 T42 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7231845 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7231846 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7231846 T42 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7231847 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7231848 T42 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7231848 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 7231849 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
[junit4] 2> 7231849 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 7231903 T42 oasc.CoreContainer.<init> New CoreContainer 32932822
[junit4] 2> 7231904 T42 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 7231907 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 7231908 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 7231908 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 7231908 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 7231909 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 7231909 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 7231909 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 7231910 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 7231910 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 7231910 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 7231913 T42 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 7231915 T42 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 7231915 T42 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 7231915 T42 oasc.CoreContainer.load Host Name:
[junit4] 2> 7231918 T49 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 7231919 T49 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 7231919 T49 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 7231919 T49 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 7231939 T49 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 7231946 T49 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 7231958 T49 oasc.SolrConfig.<init> Loaded SolrConfig: bad-solrconfig-bogus-scriptengine-name.xml
[junit4] 2> 7231959 T49 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 7231982 T49 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 7232206 T49 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 7232215 T49 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 7232217 T49 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 7232231 T49 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7232236 T49 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7232247 T49 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7232248 T49 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7232249 T49 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7232249 T49 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7232250 T49 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7232250 T49 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7232251 T49 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 7232251 T49 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 7232251 T49 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/
[junit4] 2> 7232252 T49 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 7232252 T49 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001
[junit4] 2> 7232253 T49 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index/
[junit4] 2> 7232255 T49 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "force-script-engine" (default)
[junit4] 2> 7232255 T49 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "force-script-engine" (default)
[junit4] 2> 7232256 T49 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 7232257 T49 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 7232257 T49 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 7232257 T49 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 7232258 T49 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 7232258 T49 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 7232259 T49 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 7232260 T49 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 7232260 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 7232261 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 7232261 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 7232262 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 7232262 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 7232263 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 7232263 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 7232264 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 7232264 T49 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 7232267 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 7232269 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 7232270 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 7232271 T49 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 7232272 T49 oasc.RequestHandlers.initHandlersFromConfig WARN no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 7232272 T49 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 7232273 T49 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 7232273 T49 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 7232274 T49 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index
[junit4] 2> 7232275 T49 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index;done=true>>]
[junit4] 2> 7232275 T49 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001/index
[junit4] 2> 7232276 T49 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@144c473
[junit4] 2> 7232276 T49 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 7232276 T49 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 7232277 T49 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 7232277 T49 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 7232277 T49 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 7232278 T49 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 1 directories currently being tracked
[junit4] 2> 7232278 T49 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001;done=false>>]
[junit4] 2> 7232278 T49 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001/init-core-data-001
[junit4] 2> 7232278 T49 oasc.CoreContainer.create ERROR Error creating core [collection1]: Error opening new searcher org.apache.solr.common.SolrException: Error opening new searcher
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:896)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:661)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:510)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:274)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:268)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error opening new searcher
[junit4] 2> at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1603)
[junit4] 2> at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:1715)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:867)
[junit4] 2> ... 8 more
[junit4] 2> Caused by: java.nio.channels.ClosedByInterruptException
[junit4] 2> at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
[junit4] 2> at sun.nio.ch.FileChannelImpl.size(FileChannelImpl.java:314)
[junit4] 2> at org.apache.lucene.store.NIOFSDirectory$NIOFSIndexInput.<init>(NIOFSDirectory.java:108)
[junit4] 2> at org.apache.lucene.store.NIOFSDirectory.openInput(NIOFSDirectory.java:81)
[junit4] 2> at org.apache.lucene.store.NRTCachingDirectory.openInput(NRTCachingDirectory.java:190)
[junit4] 2> at org.apache.lucene.store.Directory.openChecksumInput(Directory.java:110)
[junit4] 2> at org.apache.lucene.index.SegmentInfos.readCommit(SegmentInfos.java:282)
[junit4] 2> at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:824)
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.<init>(SolrIndexWriter.java:77)
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:64)
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.createMainIndexWriter(DefaultSolrCoreState.java:267)
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:110)
[junit4] 2> at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1572)
[junit4] 2> ... 10 more
[junit4] 2>
[junit4] 2> 7232280 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 7232280 T42 oas.SolrTestCaseJ4.tearDown ###Ending testBogusScriptEngine
[junit4] 2> 7232283 T42 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestBadConfig 3D72423A673AD680-001
[junit4] 2> 7222450 T41 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated.
[junit4] 2> 7232302 T3 oasu.SolrIndexWriter.finalize ERROR SolrIndexWriter was not closed prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!!
[junit4] 2> 7232302 T3 oasu.SolrIndexWriter.close ERROR Error closing IndexWriter java.lang.NullPointerException
[junit4] 2> at org.apache.lucene.index.IndexWriter.doFlush(IndexWriter.java:3021)
[junit4] 2> at org.apache.lucene.index.IndexWriter.flush(IndexWriter.java:2994)
[junit4] 2> at org.apache.lucene.index.IndexWriter.shutdown(IndexWriter.java:963)
[junit4] 2> at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1008)
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.close(SolrIndexWriter.java:129)
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.finalize(SolrIndexWriter.java:182)
[junit4] 2> at java.lang.System$2.invokeFinalize(System.java:1267)
[junit4] 2> at java.lang.ref.Finalizer.runFinalizer(Finalizer.java:98)
[junit4] 2> at java.lang.ref.Finalizer.access$100(Finalizer.java:34)
[junit4] 2> at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:210)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Lucene50, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=en_AU, timezone=America/Chicago
[junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.8.0_20 (32-bit)/cpus=8,threads=1,free=15939192,total=36352000
[junit4] 2> NOTE: All tests run in this JVM: [ChangedSchemaMergeTest, AddBlockUpdateTest, SolrTestCaseJ4Test, SimpleMLTQParserTest, QueryParsingTest, TestBadConfig]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBadConfig -Dtests.seed=3D72423A673AD680 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_AU -Dtests.timezone=America/Chicago -Dtests.asserts=false -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestBadConfig (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([3D72423A673AD680]:0)
[junit4] Completed on J1 in 7222.54s, 4 tests, 2 errors <<< FAILURES!
[...truncated 351 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970: There were test failures: 460 suites, 1859 tests, 1 suite-level error, 1 error, 32 ignored (22 assumptions)
Total time: 174 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_20 -client -XX:+UseConcMarkSweepGC (asserts: false)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any