Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/3012/
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:36167//collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:36167//collection1 at __randomizedtesting.SeedInfo.seed([EF6A4F27C34BA123:673E70FD6DB7CCDB]: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 10562 lines...] [junit4] Suite: org.apache.solr.handler.component.DistributedMLTComponentTest [junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/init-core-data-001 [junit4] 2> 1876141 T7613 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 1876141 T7613 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 1877908 T7613 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1 [junit4] 2> 1877911 T7613 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1877919 T7613 oejs.AbstractConnector.doStart Started [email protected]:36166 [junit4] 2> 1877919 T7613 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=36166, hostContext=/, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores} [junit4] 2> 1877920 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@57bd06bf [junit4] 2> 1877920 T7613 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/' [junit4] 2> 1877971 T7613 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/solr.xml [junit4] 2> 1878036 T7613 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores [junit4] 2> 1878037 T7613 oasc.CoreContainer.<init> New CoreContainer 198480502 [junit4] 2> 1878037 T7613 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/] [junit4] 2> 1878038 T7613 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/lib [junit4] 2> 1878039 T7613 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-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/lib). [junit4] 2> 1878052 T7613 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> 1878060 T7613 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1878061 T7613 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1878061 T7613 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1878062 T7613 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1878064 T7613 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores [junit4] 2> 1878066 T7613 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1/, dataDir=data/, name=collection1, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1, config=solrconfig.xml, transient=false, loadOnStartup=true, configSet=collection1, schema=schema.xml} [junit4] 2> 1878066 T7613 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1/ [junit4] 2> 1878067 T7613 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1878068 T7623 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1878069 T7623 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1878070 T7623 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1878070 T7623 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 1878130 T7623 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1878166 T7623 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1878270 T7623 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1878272 T7623 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1878290 T7623 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1878748 T7623 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1878765 T7623 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1878769 T7623 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1878779 T7623 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1878784 T7623 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1878788 T7623 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1878790 T7623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1878790 T7623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1878791 T7623 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1878792 T7623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1878792 T7623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1878792 T7623 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1 [junit4] 2> 1878793 T7623 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1878793 T7623 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 1878793 T7623 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@61c3e365 [junit4] 2> 1878794 T7623 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1/data [junit4] 2> 1878795 T7623 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 1878795 T7623 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1878796 T7623 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 1878796 T7623 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1878798 T7623 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7f6ef276 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cf06256),segFN=segments_1,generation=1} [junit4] 2> 1878798 T7623 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1878811 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1878812 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1878812 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1878812 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1878813 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1878813 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1878813 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1878814 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1878814 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1878814 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1878815 T7623 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1878815 T7623 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1878835 T7623 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1878838 T7623 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1878841 T7623 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1878843 T7623 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1878849 T7623 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1878852 T7623 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: lazy,spellCheckCompRH,/admin/segments,/admin/logging,/admin/threads,spellCheckCompRH_Direct,/admin/system,/debug/dump,/replication,tvrh,defaults,/search-facet-def,/terms,standard,/mlt,spellCheckWithWordbreak,mltrh,mock,/update/json/docs,/update/json,/admin/luke,/get,/config,spellCheckCompRH1,/admin/mbeans,/admin/file,/update,spellCheckWithWordbreak_Direct,/search-facet-invariants,/admin/ping,/admin/properties,/schema,/update/csv,/admin/plugins,dismax [junit4] 2> 1878854 T7623 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1878855 T7623 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1878857 T7623 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1878858 T7623 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1878859 T7623 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1878859 T7623 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1878860 T7623 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1878861 T7623 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7f6ef276 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cf06256),segFN=segments_1,generation=1} [junit4] 2> 1878861 T7623 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1878861 T7623 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@2983cbf5[collection1] main [junit4] 2> 1878862 T7623 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1878862 T7623 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1878863 T7623 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1878863 T7623 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1878894 T7623 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1878954 T7623 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1878955 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1878956 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1878956 T7623 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1878956 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1878956 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1878956 T7613 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3 [junit4] 2> 1878957 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1878957 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1878958 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1878959 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1878960 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1878961 T7624 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1878962 T7624 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2983cbf5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1880723 T7613 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 1880726 T7613 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1880730 T7613 oejs.AbstractConnector.doStart Started [email protected]:36167 [junit4] 2> 1880731 T7613 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=36167, hostContext=/, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores} [junit4] 2> 1880731 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@57bd06bf [junit4] 2> 1880732 T7613 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/' [junit4] 2> 1880810 T7613 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/solr.xml [junit4] 2> 1880870 T7613 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores [junit4] 2> 1880871 T7613 oasc.CoreContainer.<init> New CoreContainer 57021137 [junit4] 2> 1880871 T7613 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/] [junit4] 2> 1880871 T7613 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/lib [junit4] 2> 1880872 T7613 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-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/lib). [junit4] 2> 1880885 T7613 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> 1880893 T7613 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1880894 T7613 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1880895 T7613 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1880895 T7613 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1880896 T7613 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores [junit4] 2> 1880898 T7613 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {configSet=collection1, transient=false, schema=schema.xml, config=solrconfig.xml, loadOnStartup=true, dataDir=data/, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1/, name=collection1} [junit4] 2> 1880899 T7613 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 1880899 T7613 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1880900 T7635 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1880902 T7635 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1880902 T7635 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1880903 T7635 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 1880953 T7635 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1880984 T7635 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1881060 T7635 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1881061 T7635 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1881071 T7635 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1881494 T7635 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1881510 T7635 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1881513 T7635 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1881524 T7635 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1881528 T7635 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1881532 T7635 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1881533 T7635 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1881534 T7635 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1881534 T7635 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1881534 T7635 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1881535 T7635 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1881535 T7635 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1 [junit4] 2> 1881535 T7635 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1881536 T7635 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 1881536 T7635 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@61c3e365 [junit4] 2> 1881537 T7635 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 1881537 T7635 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 1881537 T7635 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1881538 T7635 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 1881538 T7635 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1881539 T7635 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24885235 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@71cd284f),segFN=segments_1,generation=1} [junit4] 2> 1881539 T7635 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1881546 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1881547 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1881547 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1881547 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1881548 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1881548 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1881548 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1881548 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1881549 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1881549 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1881549 T7635 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1881550 T7635 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1881570 T7635 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1881572 T7635 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1881575 T7635 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1881577 T7635 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1881584 T7635 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1881588 T7635 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: lazy,spellCheckCompRH,/admin/segments,/admin/logging,/admin/threads,spellCheckCompRH_Direct,/admin/system,/debug/dump,/replication,tvrh,defaults,/search-facet-def,/terms,standard,/mlt,spellCheckWithWordbreak,mltrh,mock,/update/json/docs,/update/json,/admin/luke,/get,/config,spellCheckCompRH1,/admin/mbeans,/admin/file,/update,spellCheckWithWordbreak_Direct,/search-facet-invariants,/admin/ping,/admin/properties,/schema,/update/csv,/admin/plugins,dismax [junit4] 2> 1881590 T7635 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1881591 T7635 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1881594 T7635 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1881594 T7635 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1881595 T7635 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1881596 T7635 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1881596 T7635 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1881597 T7635 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24885235 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@71cd284f),segFN=segments_1,generation=1} [junit4] 2> 1881597 T7635 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1881598 T7635 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@200337b0[collection1] main [junit4] 2> 1881598 T7635 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1881598 T7635 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1881599 T7635 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1881599 T7635 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1881719 T7635 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1881881 T7635 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1881881 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1881882 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1881883 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1881883 T7635 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1881883 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1881883 T7613 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3 [junit4] 2> 1881884 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1881884 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1881885 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1881886 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1881887 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1881888 T7636 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1881890 T7636 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@200337b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1883744 T7613 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 1883748 T7613 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1883751 T7613 oejs.AbstractConnector.doStart Started [email protected]:36168 [junit4] 2> 1883752 T7613 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=36168, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores, hostContext=/} [junit4] 2> 1883752 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@57bd06bf [junit4] 2> 1883752 T7613 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/' [junit4] 2> 1883803 T7613 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/solr.xml [junit4] 2> 1883863 T7613 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores [junit4] 2> 1883863 T7613 oasc.CoreContainer.<init> New CoreContainer 966002845 [junit4] 2> 1883864 T7613 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/] [junit4] 2> 1883864 T7613 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/lib [junit4] 2> 1883865 T7613 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-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/lib). [junit4] 2> 1883875 T7613 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> 1883882 T7613 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1883883 T7613 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1883883 T7613 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1883883 T7613 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1883885 T7613 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores [junit4] 2> 1883886 T7613 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {transient=false, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1/, name=collection1, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1, config=solrconfig.xml, loadOnStartup=true, configSet=collection1, dataDir=data/, schema=schema.xml} [junit4] 2> 1883887 T7613 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1/ [junit4] 2> 1883887 T7613 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1883888 T7647 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1883890 T7647 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1883890 T7647 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1883891 T7647 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 1883943 T7647 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1883968 T7647 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1884038 T7647 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1884039 T7647 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1884048 T7647 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1884393 T7647 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1884408 T7647 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1884410 T7647 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1884418 T7647 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1884421 T7647 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1884424 T7647 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1884425 T7647 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1884425 T7647 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1884425 T7647 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1884426 T7647 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1884426 T7647 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1884426 T7647 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1 [junit4] 2> 1884426 T7647 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1884426 T7647 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 1884427 T7647 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@61c3e365 [junit4] 2> 1884427 T7647 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1/data [junit4] 2> 1884427 T7647 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1/data/index/ [junit4] 2> 1884428 T7647 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1884428 T7647 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard1/cores/collection1/data/index [junit4] 2> 1884428 T7647 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1884429 T7647 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43e7c2cb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24a5c041),segFN=segments_1,generation=1} [junit4] 2> 1884429 T7647 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1884434 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1884434 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1884434 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1884434 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1884435 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1884435 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1884435 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1884435 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1884435 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1884436 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1884436 T7647 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1884436 T7647 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1884458 T7647 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1884460 T7647 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1884461 T7647 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1884463 T7647 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1884468 T7647 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1884471 T7647 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: lazy,spellCheckCompRH,/admin/segments,/admin/logging,/admin/threads,spellCheckCompRH_Direct,/admin/system,/debug/dump,/replication,tvrh,defaults,/search-facet-def,/terms,standard,/mlt,spellCheckWithWordbreak,mltrh,mock,/update/json/docs,/update/json,/admin/luke,/get,/config,spellCheckCompRH1,/admin/mbeans,/admin/file,/update,spellCheckWithWordbreak_Direct,/search-facet-invariants,/admin/ping,/admin/properties,/schema,/update/csv,/admin/plugins,dismax [junit4] 2> 1884472 T7647 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1884473 T7647 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1884475 T7647 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1884475 T7647 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1884476 T7647 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1884477 T7647 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1884477 T7647 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1884478 T7647 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43e7c2cb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24a5c041),segFN=segments_1,generation=1} [junit4] 2> 1884478 T7647 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1884478 T7647 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@47bbc5cb[collection1] main [junit4] 2> 1884478 T7647 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1884478 T7647 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1884479 T7647 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1884479 T7647 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1884489 T7647 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1884544 T7647 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1884544 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1884545 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1884545 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1884545 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1884545 T7647 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1884545 T7613 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3 [junit4] 2> 1884546 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1884546 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1884547 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1884547 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1884548 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1884549 T7648 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1884550 T7648 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@47bbc5cb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1886674 T7613 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1 [junit4] 2> 1886678 T7613 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1886683 T7613 oejs.AbstractConnector.doStart Started [email protected]:36171 [junit4] 2> 1886683 T7613 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=36171, coreRootDirectory=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores, hostContext=/} [junit4] 2> 1886684 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@57bd06bf [junit4] 2> 1886684 T7613 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/' [junit4] 2> 1886758 T7613 oasc.SolrXmlConfig.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/solr.xml [junit4] 2> 1886853 T7613 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores [junit4] 2> 1886854 T7613 oasc.CoreContainer.<init> New CoreContainer 44844291 [junit4] 2> 1886854 T7613 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/] [junit4] 2> 1886854 T7613 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/lib [junit4] 2> 1886856 T7613 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-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/lib). [junit4] 2> 1886869 T7613 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> 1886881 T7613 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1886882 T7613 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1886883 T7613 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1886883 T7613 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1886884 T7613 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores [junit4] 2> 1886887 T7613 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {transient=false, instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/, loadOnStartup=true, configSet=collection1, config=solrconfig.xml, dataDir=data/, schema=schema.xml, name=collection1} [junit4] 2> 1886888 T7613 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/ [junit4] 2> 1886888 T7613 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1886889 T7659 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1886891 T7659 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1886892 T7659 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1886893 T7659 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 1886964 T7659 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1886999 T7659 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1887079 T7659 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1887080 T7659 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1887092 T7659 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1887468 T7659 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1887482 T7659 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1887485 T7659 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1887492 T7659 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1887496 T7659 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1887499 T7659 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1887500 T7659 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1887500 T7659 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1887500 T7659 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1887501 T7659 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1887501 T7659 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1887501 T7659 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1 [junit4] 2> 1887501 T7659 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1887502 T7659 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 1887502 T7659 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@61c3e365 [junit4] 2> 1887503 T7659 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data [junit4] 2> 1887503 T7659 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data/index/ [junit4] 2> 1887503 T7659 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1887503 T7659 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data/index [junit4] 2> 1887503 T7659 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1887504 T7659 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1457524b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70dbc45b),segFN=segments_1,generation=1} [junit4] 2> 1887504 T7659 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1887509 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1887509 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1887510 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1887510 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1887510 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1887510 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1887510 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1887511 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1887511 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1887511 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1887511 T7659 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1887511 T7659 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1887534 T7659 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1887536 T7659 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1887537 T7659 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1887539 T7659 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1887544 T7659 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1887547 T7659 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: lazy,spellCheckCompRH,/admin/segments,/admin/logging,/admin/threads,spellCheckCompRH_Direct,/admin/system,/debug/dump,/replication,tvrh,defaults,/search-facet-def,/terms,standard,/mlt,spellCheckWithWordbreak,mltrh,mock,/update/json/docs,/update/json,/admin/luke,/get,/config,spellCheckCompRH1,/admin/mbeans,/admin/file,/update,spellCheckWithWordbreak_Direct,/search-facet-invariants,/admin/ping,/admin/properties,/schema,/update/csv,/admin/plugins,dismax [junit4] 2> 1887548 T7659 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1887550 T7659 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1887551 T7659 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1887551 T7659 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1887552 T7659 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1887552 T7659 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1887553 T7659 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1887553 T7659 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1457524b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70dbc45b),segFN=segments_1,generation=1} [junit4] 2> 1887554 T7659 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1887554 T7659 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@12147547[collection1] main [junit4] 2> 1887554 T7659 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1887554 T7659 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1887555 T7659 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1887555 T7659 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1887579 T7659 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1887650 T7659 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1887651 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1887652 T7659 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1887652 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1887652 T7613 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3 [junit4] 2> 1887653 T7613 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1887652 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1887653 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1887654 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1887654 T7613 oas.SolrTestCaseJ4.setUp ###Starting test [junit4] 2> 1887655 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1887656 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1887658 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1887659 T7660 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> ASYNC NEW_CORE C1489 name=collection1 org.apache.solr.core.SolrCore@1bfab988 [junit4] 2> 1887660 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499473264515219456)} 0 4 [junit4] 2> 1887661 T7660 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12147547[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> ASYNC NEW_CORE C1490 name=collection1 org.apache.solr.core.SolrCore@9df514f [junit4] 2> 1887666 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499473264522559488)} 0 4 [junit4] 2> ASYNC NEW_CORE C1491 name=collection1 org.apache.solr.core.SolrCore@129ae39e [junit4] 2> 1887671 T7642 c:collection1 C1491 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499473264527802368)} 0 3 [junit4] 2> ASYNC NEW_CORE C1492 name=collection1 org.apache.solr.core.SolrCore@30a3602c [junit4] 2> 1887676 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499473264533045248)} 0 3 [junit4] 2> 1887686 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1499473264539336704)]} 0 7 [junit4] 2> 1887690 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1499473264549822464)]} 0 1 [junit4] 2> 1887693 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1499473264554016768)]} 0 0 [junit4] 2> 1887697 T7642 c:collection1 C1491 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1499473264557162496)]} 0 1 [junit4] 2> 1887700 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1499473264561356800)]} 0 0 [junit4] 2> 1887704 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1499473264564502528)]} 0 1 [junit4] 2> 1887707 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1499473264568696832)]} 0 0 [junit4] 2> 1887710 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1499473264571842560)]} 0 0 [junit4] 2> 1887713 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1499473264574988288)]} 0 0 [junit4] 2> 1887716 T7642 c:collection1 C1491 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1499473264578134016)]} 0 0 [junit4] 2> 1887719 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1499473264581279744)]} 0 0 [junit4] 2> 1887722 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1499473264584425472)]} 0 0 [junit4] 2> 1887725 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1499473264587571200)]} 0 0 [junit4] 2> 1887728 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1499473264590716928)]} 0 0 [junit4] 2> 1887731 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1499473264593862656)]} 0 0 [junit4] 2> 1887734 T7642 c:collection1 C1491 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1499473264597008384)]} 0 0 [junit4] 2> 1887737 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1499473264600154112)]} 0 0 [junit4] 2> 1887740 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1499473264603299840)]} 0 0 [junit4] 2> 1887743 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1499473264606445568)]} 0 0 [junit4] 2> 1887746 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1499473264609591296)]} 0 0 [junit4] 2> 1887750 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1499473264612737024)]} 0 1 [junit4] 2> 1887753 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1499473264616931328)]} 0 0 [junit4] 2> 1887756 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1499473264620077056)]} 0 0 [junit4] 2> 1887758 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1499473264622174208)]} 0 0 [junit4] 2> 1887761 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1499473264624271360)]} 0 1 [junit4] 2> 1887764 T7642 c:collection1 C1491 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1499473264628465664)]} 0 0 [junit4] 2> 1887767 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1499473264631611392)]} 0 0 [junit4] 2> 1887769 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1499473264633708544)]} 0 0 [junit4] 2> 1887773 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1499473264637902848)]} 0 0 [junit4] 2> 1887776 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1499473264641048576)]} 0 0 [junit4] 2> 1887779 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1499473264644194304)]} 0 0 [junit4] 2> 1887782 T7642 c:collection1 C1491 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1499473264647340032)]} 0 0 [junit4] 2> 1887785 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18 (1499473264650485760)]} 0 0 [junit4] 2> 1887788 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18 (1499473264653631488)]} 0 0 [junit4] 2> 1887791 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19 (1499473264656777216)]} 0 0 [junit4] 2> 1887794 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19 (1499473264659922944)]} 0 0 [junit4] 2> 1887796 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20 (1499473264662020096)]} 0 0 [junit4] 2> 1887799 T7642 c:collection1 C1491 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20 (1499473264665165824)]} 0 0 [junit4] 2> 1887802 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21 (1499473264668311552)]} 0 0 [junit4] 2> 1887804 T7654 c:collection1 C1492 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21 (1499473264670408704)]} 0 0 [junit4] 2> 1887807 T7617 c:collection1 C1489 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22 (1499473264673554432)]} 0 0 [junit4] 2> 1887809 T7629 c:collection1 C1490 oasup.LogUpdateProcessor.finish [collection1] webapp= [...truncated too long message...] rd2/cores/collection1/data/index;done=false>>] [junit4] 2> 1978786 T7613 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data/index [junit4] 2> 1978786 T7613 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data;done=false>>] [junit4] 2> 1978786 T7613 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001/tempDir-001/shard2/cores/collection1/data [junit4] 2> 1978787 T7613 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedMLTComponentTest -Dtests.method=test -Dtests.seed=EF6A4F27C34BA123 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=sq_AL -Dtests.timezone=America/Vancouver -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 103s 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:36167//collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([EF6A4F27C34BA123:673E70FD6DB7CCDB]: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> 1978988 T7613 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedMLTComponentTest EF6A4F27C34BA123-001 [junit4] 2> NOTE: test params are: codec=Lucene50, sim=DefaultSimilarity, locale=sq_AL, timezone=America/Vancouver [junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=236767064,total=438304768 [junit4] 2> NOTE: All tests run in this JVM: [TestExtendedDismaxParser, TestLazyCores, TestPHPSerializedResponseWriter, ExitableDirectoryReaderTest, TestTolerantSearch, TestCollectionAPI, ShowFileRequestHandlerTest, TestConfigOverlay, SoftAutoCommitTest, CursorPagingTest, TestSearchPerf, FacetPivotSmallTest, TestSolrQueryParserResource, SolrRequestParserTest, FieldMutatingUpdateProcessorTest, NumericFieldsTest, TestSolrConfigHandler, TestObjectReleaseTracker, CollectionsAPIDistributedZkTest, TestDocBasedVersionConstraints, XsltUpdateRequestHandlerTest, LukeRequestHandlerTest, TestCodecSupport, DistributedFacetPivotSmallAdvancedTest, TestInitQParser, DOMUtilTest, TestSolrJ, TestConfig, BlockCacheTest, TestPivotHelperCode, TestConfigSets, PluginInfoTest, UpdateParamsTest, TestHdfsUpdateLog, CollectionReloadTest, DistribDocExpirationUpdateProcessorTest, TestOrdValues, IndexSchemaRuntimeFieldTest, HdfsWriteToMultipleCollectionsTest, TimeZoneUtilsTest, AliasIntegrationTest, TestMaxScoreQueryParser, TestBM25SimilarityFactory, BasicDistributedZk2Test, TestCloudManagedSchemaConcurrent, TestJsonFacets, UUIDUpdateProcessorFallbackTest, AutoCommitTest, SpatialRPTFieldTypeTest, TestCopyFieldCollectionResource, SolrCoreCheckLockOnStartupTest, TestSchemaNameResource, MoreLikeThisHandlerTest, SolrIndexSplitterTest, EnumFieldTest, TestHighFrequencyDictionaryFactory, TestCollapseQParserPlugin, TestJettySolrRunner, StatsComponentTest, LeaderInitiatedRecoveryOnCommitTest, UnloadDistributedZkTest, MultiThreadedOCPTest, TestSolrConfigHandlerConcurrent, TestCloudPivotFacet, OverseerRolesTest, OverseerStatusTest, TestDistribDocBasedVersion, TestManagedSchemaDynamicFieldResource, TestExactStatsCache, TestDistributedMissingSort, TestRandomDVFaceting, TestHashPartitioner, TestRealTimeGet, DistributedMLTComponentTest] [junit4] Completed [356/484] on J3 in 102.89s, 1 test, 1 error <<< FAILURES! [...truncated 407 lines...] BUILD FAILED /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:536: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:484: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:229: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:511: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1434: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:991: There were test failures: 484 suites, 1946 tests, 1 error, 68 ignored (26 assumptions) Total time: 54 minutes 52 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #3011 Archived 5 artifacts Archive block size is 32768 Received 0 blocks and 228465001 bytes Compression is 0.0% Took 44 sec Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
