You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/04/22 18:47:26 UTC
[JENKINS] Lucene-Solr-NightlyTests-5.x - Build # 825 - Still
Failing
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/825/
1 tests failed.
REGRESSION: org.apache.solr.handler.component.DistributedMLTComponentTest.test
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:55872//collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:55872//collection1
at __randomizedtesting.SeedInfo.seed([B9674711FC0D1DC6:313378CB52F1703E]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:558)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:606)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:588)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:567)
at org.apache.solr.handler.component.DistributedMLTComponentTest.test(DistributedMLTComponentTest.java:126)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
... 51 more
Build Log:
[...truncated 10599 lines...]
[junit4] Suite: org.apache.solr.handler.component.DistributedMLTComponentTest
[junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/init-core-data-001
[junit4] 2> 875523 T2287 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 875524 T2287 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 879284 T2287 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1
[junit4] 2> 879293 T2287 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 879297 T2287 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:55864
[junit4] 2> 879298 T2287 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores, hostPort=55864}
[junit4] 2> 879299 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@231bf785
[junit4] 2> 879300 T2287 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/'
[junit4] 2> 879410 T2287 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/solr.xml
[junit4] 2> 879600 T2287 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores
[junit4] 2> 879601 T2287 oasc.CoreContainer.<init> New CoreContainer 991515407
[junit4] 2> 879602 T2287 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/]
[junit4] 2> 879602 T2287 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/lib
[junit4] 2> 879603 T2287 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/lib).
[junit4] 2> 879625 T2287 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 879645 T2287 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 879648 T2287 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 879648 T2287 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 879650 T2287 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 879653 T2287 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores
[junit4] 2> 879658 T2287 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {dataDir=data/, configSet=collection1, name=collection1, loadOnStartup=true, transient=false, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1/, schema=schema.xml, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1, config=solrconfig.xml}
[junit4] 2> 879659 T2287 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1/
[junit4] 2> 879659 T2287 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 879662 T2297 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 879669 T2297 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 879670 T2297 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 879671 T2297 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 879770 T2297 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 879825 T2297 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 879914 T2297 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 879915 T2297 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 879926 T2297 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 880604 T2297 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 880639 T2297 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 880645 T2297 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 880663 T2297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 880672 T2297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 880679 T2297 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 880680 T2297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 880681 T2297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 880681 T2297 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 880682 T2297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 880682 T2297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 880683 T2297 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 880683 T2297 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 880684 T2297 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 880684 T2297 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bc1d00a
[junit4] 2> 880685 T2297 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1/data
[junit4] 2> 880686 T2297 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1/data/index/
[junit4] 2> 880686 T2297 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 880687 T2297 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/control/cores/collection1/data/index
[junit4] 2> 880687 T2297 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=59.0947265625, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=7.0218286767066775, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38394155987348166
[junit4] 2> 880689 T2297 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8fb77bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3572a134),segFN=segments_1,generation=1}
[junit4] 2> 880689 T2297 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 880701 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 880702 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 880702 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 880703 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 880703 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 880703 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 880703 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 880704 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 880704 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 880705 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 880706 T2297 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 880706 T2297 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 880776 T2297 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 880781 T2297 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 880787 T2297 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 880792 T2297 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 880802 T2297 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 880806 T2297 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /debug/dump,/terms,/admin/ping,/admin/luke,spellCheckCompRH_Direct,/update,mock,/admin/logging,/get,/admin/segments,/admin/properties,/update/csv,tvrh,/update/json,/update/json/docs,/config,/search-facet-def,/admin/mbeans,/mlt,spellCheckCompRH,/replication,mltrh,dismax,spellCheckWithWordbreak,lazy,/admin/system,/admin/threads,/admin/file,/admin/plugins,standard,spellCheckCompRH1,/search-facet-invariants,spellCheckWithWordbreak_Direct,defaults,/schema
[junit4] 2> 880810 T2297 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 880813 T2297 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 880814 T2297 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 880814 T2297 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 880815 T2297 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7806538085367347]
[junit4] 2> 880816 T2297 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8fb77bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3572a134),segFN=segments_1,generation=1}
[junit4] 2> 880817 T2297 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 880817 T2297 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@c347546[collection1] main
[junit4] 2> 880818 T2297 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 880818 T2297 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 880818 T2297 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 880819 T2297 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 880868 T2297 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 881034 T2297 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 881035 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 881037 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 881038 T2297 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 881038 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 881038 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 881039 T2287 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
[junit4] 2> 881039 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 881041 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 881042 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 881044 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 881047 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 881048 T2298 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 881051 T2298 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c347546[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 884620 T2287 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 884628 T2287 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 884641 T2287 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:55867
[junit4] 2> 884642 T2287 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=55867, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores, hostContext=/}
[junit4] 2> 884643 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@231bf785
[junit4] 2> 884643 T2287 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/'
[junit4] 2> 884752 T2287 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/solr.xml
[junit4] 2> 884893 T2287 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores
[junit4] 2> 884894 T2287 oasc.CoreContainer.<init> New CoreContainer 369845947
[junit4] 2> 884894 T2287 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/]
[junit4] 2> 884895 T2287 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/lib
[junit4] 2> 884897 T2287 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/lib).
[junit4] 2> 884920 T2287 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 884935 T2287 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 884938 T2287 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 884938 T2287 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 884939 T2287 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 884941 T2287 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores
[junit4] 2> 884946 T2287 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {schema=schema.xml, name=collection1, config=solrconfig.xml, dataDir=data/, transient=false, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1/, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1, configSet=collection1, loadOnStartup=true}
[junit4] 2> 884947 T2287 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1/
[junit4] 2> 884948 T2287 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 884950 T2309 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 884959 T2309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 884960 T2309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 884962 T2309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 885052 T2309 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 885098 T2309 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 885178 T2309 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 885179 T2309 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 885193 T2309 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 885580 T2309 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 885596 T2309 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 885599 T2309 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 885609 T2309 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 885613 T2309 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 885617 T2309 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 885618 T2309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 885618 T2309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 885618 T2309 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 885619 T2309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 885619 T2309 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 885619 T2309 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 885619 T2309 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 885620 T2309 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 885620 T2309 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bc1d00a
[junit4] 2> 885621 T2309 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1/data
[junit4] 2> 885621 T2309 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1/data/index/
[junit4] 2> 885621 T2309 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 885622 T2309 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard0/cores/collection1/data/index
[junit4] 2> 885622 T2309 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=59.0947265625, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=7.0218286767066775, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38394155987348166
[junit4] 2> 885623 T2309 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7db16d6c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b870dec),segFN=segments_1,generation=1}
[junit4] 2> 885623 T2309 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 885634 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 885635 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 885635 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 885635 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 885636 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 885636 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 885636 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 885637 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 885637 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 885638 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 885638 T2309 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 885638 T2309 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 885659 T2309 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 885661 T2309 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 885664 T2309 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 885666 T2309 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 885674 T2309 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 885679 T2309 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /debug/dump,/terms,/admin/ping,/admin/luke,spellCheckCompRH_Direct,/update,mock,/admin/logging,/get,/admin/segments,/admin/properties,/update/csv,tvrh,/update/json,/update/json/docs,/config,/search-facet-def,/admin/mbeans,/mlt,spellCheckCompRH,/replication,mltrh,dismax,spellCheckWithWordbreak,lazy,/admin/system,/admin/threads,/admin/file,/admin/plugins,standard,spellCheckCompRH1,/search-facet-invariants,spellCheckWithWordbreak_Direct,defaults,/schema
[junit4] 2> 885681 T2309 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 885682 T2309 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 885683 T2309 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 885683 T2309 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 885684 T2309 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7806538085367347]
[junit4] 2> 885684 T2309 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7db16d6c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b870dec),segFN=segments_1,generation=1}
[junit4] 2> 885685 T2309 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 885685 T2309 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@64f61ebc[collection1] main
[junit4] 2> 885685 T2309 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 885686 T2309 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 885686 T2309 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 885686 T2309 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 885735 T2309 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 885791 T2309 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 885792 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 885793 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 885793 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 885793 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 885794 T2309 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 885794 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 885794 T2287 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
[junit4] 2> 885795 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 885795 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 885796 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 885798 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 885799 T2310 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 885800 T2310 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64f61ebc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 889619 T2287 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1
[junit4] 2> 889622 T2287 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 889627 T2287 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:55868
[junit4] 2> 889628 T2287 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores, hostPort=55868}
[junit4] 2> 889629 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@231bf785
[junit4] 2> 889629 T2287 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/'
[junit4] 2> 889750 T2287 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/solr.xml
[junit4] 2> 889878 T2287 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores
[junit4] 2> 889879 T2287 oasc.CoreContainer.<init> New CoreContainer 1214965368
[junit4] 2> 889880 T2287 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/]
[junit4] 2> 889880 T2287 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/lib
[junit4] 2> 889882 T2287 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/lib).
[junit4] 2> 889903 T2287 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 889924 T2287 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 889925 T2287 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 889926 T2287 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 889926 T2287 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 889927 T2287 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores
[junit4] 2> 889932 T2287 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1/, dataDir=data/, loadOnStartup=true, config=solrconfig.xml, configSet=collection1, schema=schema.xml, transient=false, name=collection1}
[junit4] 2> 889933 T2287 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1/
[junit4] 2> 889933 T2287 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 889936 T2321 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 889939 T2321 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 889940 T2321 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 889942 T2321 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 890051 T2321 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 890106 T2321 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 890229 T2321 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 890231 T2321 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 890249 T2321 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 890792 T2321 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 890828 T2321 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 890837 T2321 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 890990 T2321 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 891000 T2321 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 891011 T2321 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 891013 T2321 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 891014 T2321 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 891014 T2321 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 891015 T2321 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 891016 T2321 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 891016 T2321 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 891017 T2321 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 891017 T2321 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 891018 T2321 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bc1d00a
[junit4] 2> 891019 T2321 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1/data
[junit4] 2> 891020 T2321 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1/data/index/
[junit4] 2> 891020 T2321 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 891021 T2321 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard1/cores/collection1/data/index
[junit4] 2> 891021 T2321 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=59.0947265625, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=7.0218286767066775, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38394155987348166
[junit4] 2> 891023 T2321 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3743c840 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@430ffe18),segFN=segments_1,generation=1}
[junit4] 2> 891023 T2321 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 891033 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 891033 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 891034 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 891034 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 891034 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 891034 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 891035 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 891035 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 891035 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 891036 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 891036 T2321 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 891036 T2321 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 891103 T2321 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 891106 T2321 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 891109 T2321 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 891114 T2321 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 891130 T2321 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 891137 T2321 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /debug/dump,/terms,/admin/ping,/admin/luke,spellCheckCompRH_Direct,/update,mock,/admin/logging,/get,/admin/segments,/admin/properties,/update/csv,tvrh,/update/json,/update/json/docs,/config,/search-facet-def,/admin/mbeans,/mlt,spellCheckCompRH,/replication,mltrh,dismax,spellCheckWithWordbreak,lazy,/admin/system,/admin/threads,/admin/file,/admin/plugins,standard,spellCheckCompRH1,/search-facet-invariants,spellCheckWithWordbreak_Direct,defaults,/schema
[junit4] 2> 891141 T2321 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 891144 T2321 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 891145 T2321 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 891145 T2321 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 891146 T2321 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7806538085367347]
[junit4] 2> 891148 T2321 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3743c840 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@430ffe18),segFN=segments_1,generation=1}
[junit4] 2> 891148 T2321 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 891149 T2321 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@467fc3ea[collection1] main
[junit4] 2> 891149 T2321 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 891150 T2321 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 891150 T2321 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 891151 T2321 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 891180 T2321 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 891313 T2321 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 891314 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 891316 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 891317 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 891317 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 891317 T2321 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 891318 T2287 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
[junit4] 2> 891319 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 891319 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 891321 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 891324 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 891327 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 891329 T2322 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 891332 T2322 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@467fc3ea[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 895175 T2287 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1
[junit4] 2> 895192 T2287 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 895199 T2287 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:55872
[junit4] 2> 895200 T2287 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=55872, hostContext=/, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores}
[junit4] 2> 895201 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@231bf785
[junit4] 2> 895202 T2287 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/'
[junit4] 2> 895311 T2287 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/solr.xml
[junit4] 2> 895455 T2287 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores
[junit4] 2> 895456 T2287 oasc.CoreContainer.<init> New CoreContainer 585432988
[junit4] 2> 895456 T2287 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/]
[junit4] 2> 895457 T2287 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/lib
[junit4] 2> 895458 T2287 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/lib).
[junit4] 2> 895480 T2287 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 895496 T2287 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 895498 T2287 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 895499 T2287 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 895499 T2287 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 895501 T2287 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores
[junit4] 2> 895505 T2287 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/, name=collection1, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1, transient=false, config=solrconfig.xml, schema=schema.xml, dataDir=data/, loadOnStartup=true, configSet=collection1}
[junit4] 2> 895506 T2287 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/
[junit4] 2> 895506 T2287 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 895507 T2333 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 895510 T2333 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 895510 T2333 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 895511 T2333 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 895566 T2333 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 895599 T2333 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 895672 T2333 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 895673 T2333 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 895684 T2333 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 896037 T2333 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 896049 T2333 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 896052 T2333 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 896059 T2333 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 896062 T2333 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 896065 T2333 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 896066 T2333 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 896066 T2333 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 896066 T2333 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 896067 T2333 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 896067 T2333 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 896067 T2333 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 896067 T2333 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 896067 T2333 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 896068 T2333 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bc1d00a
[junit4] 2> 896069 T2333 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data
[junit4] 2> 896069 T2333 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data/index/
[junit4] 2> 896069 T2333 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 896070 T2333 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data/index
[junit4] 2> 896070 T2333 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=59.0947265625, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=7.0218286767066775, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38394155987348166
[junit4] 2> 896071 T2333 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@359d924a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7bef53c5),segFN=segments_1,generation=1}
[junit4] 2> 896071 T2333 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 896077 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 896077 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 896077 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 896078 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 896078 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 896078 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 896078 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 896078 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 896079 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 896079 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 896079 T2333 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 896079 T2333 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 896092 T2333 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 896094 T2333 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 896095 T2333 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 896097 T2333 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 896103 T2333 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 896106 T2333 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /debug/dump,/terms,/admin/ping,/admin/luke,spellCheckCompRH_Direct,/update,mock,/admin/logging,/get,/admin/segments,/admin/properties,/update/csv,tvrh,/update/json,/update/json/docs,/config,/search-facet-def,/admin/mbeans,/mlt,spellCheckCompRH,/replication,mltrh,dismax,spellCheckWithWordbreak,lazy,/admin/system,/admin/threads,/admin/file,/admin/plugins,standard,spellCheckCompRH1,/search-facet-invariants,spellCheckWithWordbreak_Direct,defaults,/schema
[junit4] 2> 896108 T2333 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 896109 T2333 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 896109 T2333 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 896109 T2333 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 896110 T2333 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7806538085367347]
[junit4] 2> 896111 T2333 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@359d924a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7bef53c5),segFN=segments_1,generation=1}
[junit4] 2> 896111 T2333 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 896111 T2333 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@10f96361[collection1] main
[junit4] 2> 896111 T2333 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 896112 T2333 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 896112 T2333 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 896112 T2333 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 896122 T2333 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 896189 T2333 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 896189 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 896190 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 896191 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 896191 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 896192 T2333 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 896192 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 896192 T2287 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
[junit4] 2> 896192 T2287 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 896193 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 896194 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 896194 T2287 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> 896195 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 896196 T2334 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> ASYNC NEW_CORE C232 name=collection1 org.apache.solr.core.SolrCore@2e9dac10
[junit4] 2> 896197 T2292 c:collection1 C232 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 896197 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 896198 T2334 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10f96361[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> ASYNC NEW_CORE C233 name=collection1 org.apache.solr.core.SolrCore@7cf37e16
[junit4] 2> 896200 T2305 c:collection1 C233 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 896200 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C234 name=collection1 org.apache.solr.core.SolrCore@4c9d3d9
[junit4] 2> 896203 T2314 c:collection1 C234 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 896203 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C235 name=collection1 org.apache.solr.core.SolrCore@47b64217
[junit4] 2> 896205 T2330 c:collection1 C235 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 896206 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 896211 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1]} 0 2
[junit4] 2> 896217 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1]} 0 3
[junit4] 2> 896223 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2]} 0 2
[junit4] 2> 896228 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2]} 0 2
[junit4] 2> 896232 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4] 2> 896237 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3]} 0 2
[junit4] 2> 896240 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4] 2> 896242 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 896245 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 896249 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5]} 0 1
[junit4] 2> 896253 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4] 2> 896257 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4] 2> 896261 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7]} 0 1
[junit4] 2> 896265 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7]} 0 1
[junit4] 2> 896269 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4] 2> 896273 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4] 2> 896278 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9]} 0 2
[junit4] 2> 896283 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9]} 0 2
[junit4] 2> 896286 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 896290 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4] 2> 896294 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12]} 0 1
[junit4] 2> 896298 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12]} 0 1
[junit4] 2> 896302 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13]} 0 1
[junit4] 2> 896307 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13]} 0 2
[junit4] 2> 896312 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14]} 0 2
[junit4] 2> 896317 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14]} 0 2
[junit4] 2> 896321 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4] 2> 896325 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4] 2> 896329 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16]} 0 1
[junit4] 2> 896334 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16]} 0 2
[junit4] 2> 896340 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17]} 0 3
[junit4] 2> 896345 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17]} 0 2
[junit4] 2> 896349 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18]} 0 1
[junit4] 2> 896353 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18]} 0 1
[junit4] 2> 896357 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19]} 0 1
[junit4] 2> 896361 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19]} 0 1
[junit4] 2> 896364 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 896368 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4] 2> 896371 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21]} 0 1
[junit4] 2> 896375 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21]} 0 2
[junit4] 2> 896380 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22]} 0 2
[junit4] 2> 896384 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22]} 0 1
[junit4] 2> 896389 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[23]} 0 2
[junit4] 2> 896392 T2314 c:collection1 C234 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[23]} 0 1
[junit4] 2> 896395 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[24]} 0 1
[junit4] 2> 896399 T2330 c:collection1 C235 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[24]} 0 1
[junit4] 2> 896403 T2292 c:collection1 C232 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[25]} 0 1
[junit4] 2> 896406 T2305 c:collection1 C233 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 896409 T2292 c:collection1 C232 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 896413 T2292 c:collection1 C232 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8fb77bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3572a134),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8fb77bf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3572a134),segFN=segments_2,generation=2}
[junit4] 2> 896413 T2292 c:collection1 C232 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 896415 T2292 c:collection1 C232 oass.SolrIndexSearcher.<init> Opening Searcher@30005187[c
[...truncated too long message...]
ightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data/index;done=false>>]
[junit4] 2> 987566 T2287 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data/index
[junit4] 2> 987567 T2287 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data;done=false>>]
[junit4] 2> 987567 T2287 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001/tempDir-001/shard2/cores/collection1/data
[junit4] 2> 987568 T2287 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedMLTComponentTest -Dtests.method=test -Dtests.seed=B9674711FC0D1DC6 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=ar_IQ -Dtests.timezone=Asia/Novosibirsk -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 112s J3 | DistributedMLTComponentTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:55872//collection1
[junit4] > at __randomizedtesting.SeedInfo.seed([B9674711FC0D1DC6:313378CB52F1703E]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:558)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:606)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:588)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:567)
[junit4] > at org.apache.solr.handler.component.DistributedMLTComponentTest.test(DistributedMLTComponentTest.java:126)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:152)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:122)
[junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
[junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
[junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
[junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466)
[junit4] > ... 51 more
[junit4] 2> 987783 T2287 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest B9674711FC0D1DC6-001
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3, maxDocsPerChunk=1, blockSize=169), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3, blockSize=169)), sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=ar_IQ, timezone=Asia/Novosibirsk
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=65440120,total=279969792
[junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, SampleTest, TestWordDelimiterFilterFactory, TestUtils, CSVRequestHandlerTest, TestGroupingSearch, NumericFieldsTest, TestSolrQueryParserDefaultOperatorResource, TestBulkSchemaAPI, TestCodecSupport, ClusterStateTest, TestDistributedGrouping, XsltUpdateRequestHandlerTest, CurrencyFieldXmlFileTest, SuggesterWFSTTest, TestRangeQuery, TestSolrQueryParserResource, TestManagedSchemaFieldResource, BJQParserTest, TestRestoreCore, DocValuesMultiTest, OutputWriterTest, SoftAutoCommitTest, PreAnalyzedUpdateProcessorTest, TestValueSourceCache, AlternateDirectoryTest, TestIndexingPerformance, BadCopyFieldTest, TestSimpleQParserPlugin, LeaderFailoverAfterPartitionTest, TestStandardQParsers, IndexSchemaRuntimeFieldTest, TestBlobHandler, CoreAdminHandlerTest, SaslZkACLProviderTest, TestPivotHelperCode, TestRecoveryHdfs, UnloadDistributedZkTest, FileUtilsTest, HdfsUnloadDistributedZkTest, TestRandomMergePolicy, TestEmbeddedSolrServerConstructors, TestSchemaManager, TestBlendedInfixSuggestions, TestCloudManagedSchema, JsonLoaderTest, DistributedMLTComponentTest]
[junit4] Completed [166/483] on J3 in 112.31s, 1 test, 1 error <<< FAILURES!
[...truncated 1048 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:543: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:484: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:229: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:511: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1434: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:991: There were test failures: 483 suites, 1921 tests, 1 error, 61 ignored (11 assumptions)
Total time: 162 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #823
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 464705835 bytes
Compression is 0.0%
Took 1 min 22 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-NightlyTests-5.x - Build # 826 - Still
Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/826/
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
ERROR: SolrIndexSearcher opens=32 closes=31
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=32 closes=31
at __randomizedtesting.SeedInfo.seed([A088A90F4C720569]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:496)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:232)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
4 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 1) Thread[id=681, name=searcherExecutor-539-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) 2) Thread[id=673, name=qtp628691414-673, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397) at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103) at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649) at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53) at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240) at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72) at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:745) 3) Thread[id=672, name=qtp628691414-672, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397) at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103) at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649) at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53) at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240) at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72) at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:745) 4) Thread[id=1060, name=qtp628691414-1060, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397) at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103) at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649) at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53) at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240) at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72) at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest:
1) Thread[id=681, name=searcherExecutor-539-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
2) Thread[id=673, name=qtp628691414-673, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397)
at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229)
at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782)
at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53)
at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72)
at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:745)
3) Thread[id=672, name=qtp628691414-672, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397)
at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229)
at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782)
at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53)
at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72)
at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:745)
4) Thread[id=1060, name=qtp628691414-1060, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397)
at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229)
at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782)
at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53)
at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72)
at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([A088A90F4C720569]:0)
REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=1921, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1921, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:15181: Could not find collection : awholynewstresscollection_collection4_4
at __randomizedtesting.SeedInfo.seed([A088A90F4C720569]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1074)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:846)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:789)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:884)
Build Log:
[...truncated 10346 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/init-core-data-001
[junit4] 2> 343739 T1378 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 343740 T1378 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 343748 T1378 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 343748 T1379 oasc.ZkTestServer$2$1.setClientPort client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 343748 T1379 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 343848 T1378 oasc.ZkTestServer.run start zk server on port:49518
[junit4] 2> 343849 T1378 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 343850 T1378 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 343855 T1386 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@109d0ee6 name:ZooKeeperConnection Watcher:127.0.0.1:49518 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 343856 T1378 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 343857 T1378 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 343857 T1378 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 343861 T1378 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 343862 T1378 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 343864 T1389 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@31c781a8 name:ZooKeeperConnection Watcher:127.0.0.1:49518/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 343864 T1378 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 343865 T1378 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 343865 T1378 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 343867 T1378 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 343869 T1378 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 343870 T1378 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 343873 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 343874 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 343876 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 343877 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 343880 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 343881 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 343883 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 343884 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 343886 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 343887 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 343889 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 343890 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 343892 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 343893 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 343896 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 343896 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 343899 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 343899 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 343902 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 343902 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 343905 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 343905 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 343907 T1378 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 343908 T1378 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 343910 T1392 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5ca0125f name:ZooKeeperConnection Watcher:127.0.0.1:49518 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 343910 T1378 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 343911 T1378 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 343911 T1378 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 343913 T1378 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 343914 T1378 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 343916 T1395 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@384fa2e4 name:ZooKeeperConnection Watcher:127.0.0.1:49518/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 343916 T1378 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 343917 T1378 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 343919 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
[junit4] 2> 343920 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 343923 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
[junit4] 2> 343924 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 343927 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
[junit4] 2> 343929 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/enumsConfig.xml
[junit4] 2> 343934 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 343937 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 343941 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
[junit4] 2> 343941 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 343944 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
[junit4] 2> 343946 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 343950 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
[junit4] 2> 343952 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 343955 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
[junit4] 2> 343956 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 343959 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 343960 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 343962 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
[junit4] 2> 343963 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 343964 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
[junit4] 2> 343965 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 343968 T1378 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
[junit4] 2> 343968 T1378 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 349633 T1378 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1
[junit4] 2> 349642 T1378 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 349651 T1378 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:15181
[junit4] 2> 349652 T1378 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores, hostContext=/, hostPort=15181}
[junit4] 2> 349653 T1378 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@631d75b9
[junit4] 2> 349653 T1378 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/'
[junit4] 2> 349870 T1378 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/solr.xml
[junit4] 2> 350184 T1378 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores
[junit4] 2> 350185 T1378 oasc.CoreContainer.<init> New CoreContainer 1560816660
[junit4] 2> 350185 T1378 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/]
[junit4] 2> 350185 T1378 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/lib
[junit4] 2> 350189 T1378 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/lib).
[junit4] 2> 350210 T1378 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 350237 T1378 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 350239 T1378 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 350239 T1378 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 350240 T1378 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 350240 T1378 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49518/solr
[junit4] 2> 350241 T1378 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 350241 T1378 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 350242 T1378 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 350245 T1407 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a4e3405 name:ZooKeeperConnection Watcher:127.0.0.1:49518 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 350245 T1378 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 350246 T1378 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 350248 T1378 N:127.0.0.1:15181_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 350250 T1410 N:127.0.0.1:15181_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@49530da3 name:ZooKeeperConnection Watcher:127.0.0.1:49518/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 350250 T1378 N:127.0.0.1:15181_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 350252 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 350254 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 350257 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 350259 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 350262 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 350264 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 350266 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 350268 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 350270 T1378 N:127.0.0.1:15181_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:15181_
[junit4] 2> 350271 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:15181_
[junit4] 2> 350273 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 350274 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 350276 T1378 N:127.0.0.1:15181_ oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 350278 T1378 N:127.0.0.1:15181_ oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:15181_
[junit4] 2> 350278 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 350280 T1378 N:127.0.0.1:15181_ oasc.Overseer.start Overseer (id=93703519777062917-127.0.0.1:15181_-n_0000000000) starting
[junit4] 2> 350283 T1378 N:127.0.0.1:15181_ oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 350290 T1378 N:127.0.0.1:15181_ oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 350291 T1412 N:127.0.0.1:15181_ oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 350291 T1378 N:127.0.0.1:15181_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 350291 T1411 N:127.0.0.1:15181_ oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 350297 T1378 N:127.0.0.1:15181_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores
[junit4] 2> 350305 T1378 N:127.0.0.1:15181_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {collection=control_collection, coreNodeName=, dataDir=data/, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/, config=solrconfig.xml, name=collection1, schema=schema.xml, shard=, loadOnStartup=true, transient=false}
[junit4] 2> 350305 T1378 N:127.0.0.1:15181_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/
[junit4] 2> 350306 T1378 N:127.0.0.1:15181_ oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 350307 T1414 N:127.0.0.1:15181_ C:control_collection c:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 350307 T1414 N:127.0.0.1:15181_ C:control_collection c:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 350309 T1414 N:127.0.0.1:15181_ oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 350309 T1410 N:127.0.0.1:15181_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 350310 T1411 N:127.0.0.1:15181_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:15181",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"1",
[junit4] 2> "node_name":"127.0.0.1:15181_"} current state version: 0
[junit4] 2> 350310 T1411 N:127.0.0.1:15181_ oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:15181",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"1",
[junit4] 2> "node_name":"127.0.0.1:15181_"}
[junit4] 2> 350311 T1411 N:127.0.0.1:15181_ oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 350311 T1411 N:127.0.0.1:15181_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 350313 T1410 N:127.0.0.1:15181_ 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> 351309 T1414 N:127.0.0.1:15181_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 351310 T1414 N:127.0.0.1:15181_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 351311 T1414 N:127.0.0.1:15181_ oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 351311 T1414 N:127.0.0.1:15181_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 351312 T1414 N:127.0.0.1:15181_ oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 351313 T1414 N:127.0.0.1:15181_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/'
[junit4] 2> 351344 T1414 N:127.0.0.1:15181_ oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 351367 T1414 N:127.0.0.1:15181_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 351405 T1414 N:127.0.0.1:15181_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 351484 T1414 N:127.0.0.1:15181_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 351486 T1414 N:127.0.0.1:15181_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 351504 T1414 N:127.0.0.1:15181_ oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 352195 T1414 N:127.0.0.1:15181_ oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 352231 T1414 N:127.0.0.1:15181_ oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 352237 T1414 N:127.0.0.1:15181_ oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 352256 T1414 N:127.0.0.1:15181_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 352263 T1414 N:127.0.0.1:15181_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 352272 T1414 N:127.0.0.1:15181_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 352273 T1414 N:127.0.0.1:15181_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 352273 T1414 N:127.0.0.1:15181_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 352274 T1414 N:127.0.0.1:15181_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 352275 T1414 N:127.0.0.1:15181_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 352275 T1414 N:127.0.0.1:15181_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 352275 T1414 N:127.0.0.1:15181_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 352276 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 352276 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/], dataDir=[null]
[junit4] 2> 352277 T1414 N:127.0.0.1:15181_ c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6377746a
[junit4] 2> 352278 T1414 N:127.0.0.1:15181_ c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/data
[junit4] 2> 352278 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/data/index/
[junit4] 2> 352279 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 352279 T1414 N:127.0.0.1:15181_ c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/control-001/cores/collection1/data/index
[junit4] 2> 352280 T1414 N:127.0.0.1:15181_ c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@3b750121
[junit4] 2> 352281 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@7e9d3c05 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cfad34),segFN=segments_1,generation=1}
[junit4] 2> 352282 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 352300 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 352302 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 352303 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 352303 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 352303 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 352303 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 352304 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 352305 T1414 N:127.0.0.1:15181_ c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 352305 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 352378 T1414 N:127.0.0.1:15181_ c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 352384 T1414 N:127.0.0.1:15181_ c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 352389 T1414 N:127.0.0.1:15181_ c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 352392 T1414 N:127.0.0.1:15181_ c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 352398 T1414 N:127.0.0.1:15181_ c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/properties,/update/json/docs,/update,/admin/segments,/admin/threads,/get,/admin/file,/config,/admin/mbeans,standard,/admin/plugins,/admin/luke,/admin/logging,/admin/system,/admin/ping,/replication,/update/csv,/schema,/update/json
[junit4] 2> 352401 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 352405 T1414 N:127.0.0.1:15181_ c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 352405 T1414 N:127.0.0.1:15181_ c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 352412 T1414 N:127.0.0.1:15181_ c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 352412 T1414 N:127.0.0.1:15181_ c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 352413 T1414 N:127.0.0.1:15181_ c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 352414 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@7e9d3c05 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cfad34),segFN=segments_1,generation=1}
[junit4] 2> 352414 T1414 N:127.0.0.1:15181_ c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 352415 T1414 N:127.0.0.1:15181_ c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@44ffa0c4[collection1] main
[junit4] 2> 352415 T1414 N:127.0.0.1:15181_ c:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 352417 T1414 N:127.0.0.1:15181_ c:collection1 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 352417 T1414 N:127.0.0.1:15181_ c:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 352418 T1414 N:127.0.0.1:15181_ c:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 352418 T1414 N:127.0.0.1:15181_ c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 352418 T1414 N:127.0.0.1:15181_ c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 352419 T1414 N:127.0.0.1:15181_ c:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 352420 T1414 N:127.0.0.1:15181_ c:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 352420 T1414 N:127.0.0.1:15181_ c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 352421 T1414 N:127.0.0.1:15181_ c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 352422 T1414 N:127.0.0.1:15181_ c:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 352422 T1415 N:127.0.0.1:15181_ c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44ffa0c4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 352422 T1414 N:127.0.0.1:15181_ c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 352424 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:15181 collection:control_collection shard:shard1
[junit4] 2> 352424 T1378 N:127.0.0.1:15181_ oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3
[junit4] 2> 352425 T1378 N:127.0.0.1:15181_ oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 352426 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 352430 T1378 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 352431 T1378 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 352432 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 352433 T1421 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@29919151 name:ZooKeeperConnection Watcher:127.0.0.1:49518/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 352433 T1378 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 352434 T1378 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 352435 T1378 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 352435 T1410 N:127.0.0.1:15181_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 352435 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 352436 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C106 name=collection1 org.apache.solr.core.SolrCore@7d04ddb3 url=http://127.0.0.1:15181/collection1 node=127.0.0.1:15181_ C106_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:15181, state=down, node_name=127.0.0.1:15181_}
[junit4] 2> 352436 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 C106 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:15181/collection1/
[junit4] 2> 352436 T1411 N:127.0.0.1:15181_ 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> 352436 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 C106 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 352437 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 C106 oasc.SyncStrategy.syncToMe http://127.0.0.1:15181/collection1/ has no replicas
[junit4] 2> 352437 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:15181/collection1/ shard1
[junit4] 2> 352438 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 352438 T1378 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 352439 T1421 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> 352439 T1410 N:127.0.0.1:15181_ 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> 352444 T1410 N:127.0.0.1:15181_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 352445 T1411 N:127.0.0.1:15181_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:15181",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 352550 T1410 N:127.0.0.1:15181_ 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> 352550 T1421 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> 352597 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ZkController.register We are http://127.0.0.1:15181/collection1/ and leader is http://127.0.0.1:15181/collection1/
[junit4] 2> 352598 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:15181
[junit4] 2> 352598 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 352598 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 352599 T1418 N:127.0.0.1:15181_ C:control_collection S:shard1 c:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 352600 T1410 N:127.0.0.1:15181_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 352602 T1411 N:127.0.0.1:15181_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:15181",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:15181_"} current state version: 3
[junit4] 2> 352603 T1411 N:127.0.0.1:15181_ oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:15181",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:15181_"}
[junit4] 2> 352707 T1421 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> 352707 T1410 N:127.0.0.1:15181_ 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> 355175 T1378 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1
[junit4] 2> 355178 T1378 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001
[junit4] 2> 355180 T1378 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 355182 T1378 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:15207
[junit4] 2> 355183 T1378 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=15207, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores, solrconfig=solrconfig.xml}
[junit4] 2> 355183 T1378 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@631d75b9
[junit4] 2> 355184 T1378 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/'
[junit4] 2> 355235 T1378 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/solr.xml
[junit4] 2> 355299 T1378 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores
[junit4] 2> 355299 T1378 oasc.CoreContainer.<init> New CoreContainer 962952422
[junit4] 2> 355300 T1378 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/]
[junit4] 2> 355300 T1378 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/lib
[junit4] 2> 355301 T1378 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/lib).
[junit4] 2> 355314 T1378 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 355323 T1378 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 355324 T1378 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 355324 T1378 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 355325 T1378 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 355325 T1378 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49518/solr
[junit4] 2> 355326 T1378 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 355326 T1378 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 355327 T1378 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 355328 T1433 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16bb5ba6 name:ZooKeeperConnection Watcher:127.0.0.1:49518 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 355329 T1378 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 355329 T1378 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 355331 T1378 N:127.0.0.1:15207_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 355332 T1436 N:127.0.0.1:15207_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b9f1a8b name:ZooKeeperConnection Watcher:127.0.0.1:49518/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 355332 T1378 N:127.0.0.1:15207_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 355336 T1378 N:127.0.0.1:15207_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 356339 T1378 N:127.0.0.1:15207_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:15207_
[junit4] 2> 356340 T1378 N:127.0.0.1:15207_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:15207_
[junit4] 2> 356343 T1378 N:127.0.0.1:15207_ oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 356346 T1378 N:127.0.0.1:15207_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores
[junit4] 2> 356348 T1378 N:127.0.0.1:15207_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/, schema=schema.xml, collection=collection1, shard=, config=solrconfig.xml, coreNodeName=, loadOnStartup=true, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1, name=collection1, dataDir=data/, transient=false}
[junit4] 2> 356349 T1378 N:127.0.0.1:15207_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/
[junit4] 2> 356349 T1378 N:127.0.0.1:15207_ oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 356350 T1437 N:127.0.0.1:15207_ C:collection1 c:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 356351 T1437 N:127.0.0.1:15207_ C:collection1 c:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 356351 T1437 N:127.0.0.1:15207_ oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 356352 T1410 N:127.0.0.1:15181_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 356352 T1411 N:127.0.0.1:15181_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:15207",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:15207_"} current state version: 4
[junit4] 2> 356353 T1411 N:127.0.0.1:15181_ oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:15207",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "node_name":"127.0.0.1:15207_"}
[junit4] 2> 356353 T1411 N:127.0.0.1:15181_ oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 356353 T1411 N:127.0.0.1:15181_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 356354 T1421 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> 356354 T1436 N:127.0.0.1:15207_ 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> 356354 T1410 N:127.0.0.1:15181_ 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> 357352 T1437 N:127.0.0.1:15207_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 357353 T1437 N:127.0.0.1:15207_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 357354 T1437 N:127.0.0.1:15207_ oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 357354 T1437 N:127.0.0.1:15207_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 357355 T1437 N:127.0.0.1:15207_ oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 357355 T1437 N:127.0.0.1:15207_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/'
[junit4] 2> 357381 T1437 N:127.0.0.1:15207_ oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 357399 T1437 N:127.0.0.1:15207_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 357454 T1437 N:127.0.0.1:15207_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 357528 T1437 N:127.0.0.1:15207_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 357529 T1437 N:127.0.0.1:15207_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 357551 T1437 N:127.0.0.1:15207_ oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 358205 T1437 N:127.0.0.1:15207_ oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 358229 T1437 N:127.0.0.1:15207_ oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 358233 T1437 N:127.0.0.1:15207_ oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 358255 T1437 N:127.0.0.1:15207_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 358262 T1437 N:127.0.0.1:15207_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 358270 T1437 N:127.0.0.1:15207_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 358271 T1437 N:127.0.0.1:15207_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 358271 T1437 N:127.0.0.1:15207_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 358272 T1437 N:127.0.0.1:15207_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 358273 T1437 N:127.0.0.1:15207_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 358273 T1437 N:127.0.0.1:15207_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 358273 T1437 N:127.0.0.1:15207_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 358274 T1437 N:127.0.0.1:15207_ c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 358274 T1437 N:127.0.0.1:15207_ c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/], dataDir=[null]
[junit4] 2> 358274 T1437 N:127.0.0.1:15207_ c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6377746a
[junit4] 2> 358275 T1437 N:127.0.0.1:15207_ c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/data
[junit4] 2> 358276 T1437 N:127.0.0.1:15207_ c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 358276 T1437 N:127.0.0.1:15207_ c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 358276 T1437 N:127.0.0.1:15207_ c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest A088A90F4C720569-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 358277 T1437 N:127.0.0.1:15207_ c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@66acb478
[junit4] 2> 358278 T1437 N:127.0.0.1:15207_ c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@547c66f1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@334a838c),segFN=segments_1,generation=1}
[junit4] 2> 358278 T1437 N:127.0.0.1:15207_ c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 358289 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 358289 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 358289 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 358290 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 358290 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 358290 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 358291 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 358291 T1437 N:127.0.0.1:15207_ c:collection1 oasup.UpdateRequestProcessorChain.init insertin
[...truncated too long message...]
edUpdateProcessor.java:782)
[junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
[junit4] > at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
[junit4] > at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] > at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] > at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] > at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72)
[junit4] > at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > 4) Thread[id=1060, name=qtp628691414-1060, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:503)
[junit4] > at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:397)
[junit4] > at org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:229)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)
[junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:782)
[junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1649)
[junit4] > at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
[junit4] > at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] > at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] > at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] > at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72)
[junit4] > at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([A088A90F4C720569]:0)
[junit4] Completed [135/483] on J2 in 669.85s, 1 test, 1 failure, 1 error <<< FAILURES!
[...truncated 1164 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:543: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:484: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:229: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:512: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1434: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:991: There were test failures: 483 suites, 1931 tests, 2 suite-level errors, 1 error, 61 ignored (11 assumptions)
Total time: 161 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #823
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 894151573 bytes
Compression is 0.0%
Took 2 min 46 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure