You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/09/19 19:20:45 UTC
[JENKINS] Lucene-Solr-5.x-MacOSX (64bit/jdk1.8.0) - Build # 1800 -
Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/1800/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.SolrSchemalessExampleTest.testAddDelete
Error Message:
IOException occured when talking to server at: https://127.0.0.1:57127/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:57127/solr/collection1
at __randomizedtesting.SeedInfo.seed([50B839B6A66F747E:985844BC57C7A7A8]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:563)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
at org.apache.solr.client.solrj.SolrExampleTestsBase.testAddDelete(SolrExampleTestsBase.java:186)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448)
... 46 more
Build Log:
[...truncated 12344 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrSchemalessExampleTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/init-core-data-001
[junit4] 2> 238974 T378 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 239871 T378 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 239871 T378 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 239874 T378 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 239897 T378 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 239933 T378 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:57127
[junit4] 2> 239948 T378 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 239948 T378 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 239949 T378 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001
[junit4] 2> 239949 T378 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/'
[junit4] 2> 240012 T378 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/solr.xml
[junit4] 2> 240035 T378 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001
[junit4] 2> 240036 T378 oasc.CoreContainer.<init> New CoreContainer 1912704909
[junit4] 2> 240036 T378 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/]
[junit4] 2> 240036 T378 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 240037 T378 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 240037 T378 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 240037 T378 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 240037 T378 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 240038 T378 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 240038 T378 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 240038 T378 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 240039 T378 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 240041 T378 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 240044 T378 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 240045 T378 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 240045 T378 oasc.CoreContainer.load Host Name:
[junit4] 2> 240047 T378 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001
[junit4] 2> 240061 T378 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/
[junit4] 2> 240062 T378 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 240063 T388 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/'
[junit4] 2> 240105 T388 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 240107 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../contrib/extraction/lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../contrib/extraction/lib).
[junit4] 2> 240108 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 240108 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../contrib/clustering/lib/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../contrib/clustering/lib).
[junit4] 2> 240109 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 240110 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../contrib/langid/lib/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../contrib/langid/lib).
[junit4] 2> 240110 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 240112 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../contrib/velocity/lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../contrib/velocity/lib).
[junit4] 2> 240112 T388 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/../../../../dist).
[junit4] 2> 240154 T388 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 240197 T388 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 240225 T388 oass.ManagedIndexSchemaFactory.readSchemaLocally The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 240240 T388 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 240247 T388 oass.IndexSchema.readSchema [collection1] Schema name=example-schemaless
[junit4] 2> 240590 T388 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.ThaiWordFilterFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 240628 T388 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 240700 T388 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 240708 T388 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 240779 T388 oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 240782 T388 oass.ManagedIndexSchemaFactory.upgradeToManagedSchema After upgrading to managed schema, renamed the non-managed schema /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf/schema.xml to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf/schema.xml.bak
[junit4] 2> 240784 T388 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/
[junit4] 2> 240784 T388 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 240784 T388 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002/
[junit4] 2> 240785 T388 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 240785 T388 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 240786 T388 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 240806 T388 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002
[junit4] 2> 240807 T388 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002/index/
[junit4] 2> 240807 T388 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002/index' doesn't exist. Creating new index...
[junit4] 2> 240807 T388 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002/index
[junit4] 2> 240808 T388 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_1,generation=1}
[junit4] 2> 240809 T388 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 240810 T388 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 240810 T388 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 240811 T388 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 240811 T388 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 240812 T388 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 240819 T388 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 242047 T388 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 242048 T388 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 242048 T388 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 242048 T388 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 242049 T388 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 242049 T388 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 242050 T388 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4] 2> 242050 T388 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 242050 T388 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4] 2> 242050 T388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 242050 T388 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 242051 T388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 242051 T388 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 242052 T388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 242052 T388 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 242053 T388 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4] 2> 242053 T388 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 242054 T388 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 242055 T388 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 242055 T388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 242056 T388 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4] 2> 242057 T388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 242057 T388 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4] 2> 242057 T388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 242058 T388 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4] 2> 242058 T388 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 242059 T388 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4] 2> 242068 T388 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 242071 T388 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 242076 T388 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 242090 T388 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 242100 T388 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 242101 T388 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 242107 T388 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_1,generation=1}
[junit4] 2> 242107 T388 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 242108 T388 oass.SolrIndexSearcher.<init> Opening Searcher@4e7c6156[collection1] main
[junit4] 2> 242110 T388 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf
[junit4] 2> 242110 T388 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf}
[junit4] 2> 242110 T388 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf
[junit4] 2> 242112 T388 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 242115 T388 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf
[junit4] 2> 242115 T388 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 242115 T388 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 242116 T388 oass.DirectSolrSpellChecker.init init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 242116 T388 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4] 2> 242117 T388 oashc.QueryElevationComponent.inform Loading QueryElevation from: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf/elevate.xml
[junit4] 2> 242278 T388 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 242280 T388 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 242280 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4e7c6156[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 242280 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 242280 T389 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 242280 T389 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 242281 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e7c6156[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 242282 T378 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 242289 T378 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 242290 T378 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#57127
[junit4] 2> 242311 T378 oas.SolrTestCaseJ4.setUp ###Starting testStreamingRequest
[junit4] 2> ASYNC NEW_CORE C42 name=collection1 org.apache.solr.core.SolrCore@78317de6
[junit4] 2> 242605 T383 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1479694569706618880)} 0 151
[junit4] 2> 242650 T386 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 242659 T386 C42 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 242672 T386 C42 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 242679 T386 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 242692 T386 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 44
[junit4] 2> 242738 T385 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=8
[junit4] 2> 243235 T382 C42 oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 243252 T382 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 243260 T382 C42 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_2,generation=2}
[junit4] 2> 243261 T382 C42 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 243264 T382 C42 oass.SolrIndexSearcher.<init> Opening Searcher@3ff1c224[collection1] main
[junit4] 2> 243265 T382 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 243267 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3ff1c224[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C10))}
[junit4] 2> 243268 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 243268 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ff1c224[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C10))}
[junit4] 2> 243273 T382 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {add=[0 (1479694569895362560), 1 (1479694570406019072), 2 (1479694570407067648), 3 (1479694570408116224), 4 (1479694570409164800), 5 (1479694570409164801), 6 (1479694570409164802), 7 (1479694570410213376), 8 (1479694570410213377), 9 (1479694570411261952)],commit=} 0 514
[junit4] 2> 243283 T383 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&fl=id,score,_docid_&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 2> 243290 T386 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&fl=id,score,_docid_&wt=javabin&version=2} hits=10 status=0 QTime=3
[junit4] 2> 243292 T378 oas.SolrTestCaseJ4.tearDown ###Ending testStreamingRequest
[junit4] 2> 243308 T378 oas.SolrTestCaseJ4.setUp ###Starting testAddDelete
[junit4] 2> 243317 T387 C42 oass.SolrIndexSearcher.<init> Opening Searcher@1072e217[collection1] realtime
[junit4] 2> 243318 T387 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1479694570475225088)} 0 5
[junit4] 2> 243330 T385 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1479694570489905152)]} 0 3
[junit4] 2> 243364 T384 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 243386 T384 C42 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_3,generation=3}
[junit4] 2> 243387 T384 C42 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 243395 T384 C42 oass.SolrIndexSearcher.<init> Opening Searcher@4832b164[collection1] main
[junit4] 2> 243396 T384 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 243397 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4832b164[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0.0):C1))}
[junit4] 2> 243397 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 243397 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4832b164[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0.0):C1))}
[junit4] 2> 243414 T384 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 50
[junit4] 2> 243422 T382 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 2> 243442 T383 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1479694570604199936)]} 0 6
[junit4] 2> 258457 T391 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 258459 T391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_4,generation=4}
[junit4] 2> 258459 T391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 258461 T391 oass.SolrIndexSearcher.<init> Opening Searcher@6c2a09b[collection1] realtime
[junit4] 2> 258462 T391 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 443508 T378 oas.SolrTestCaseJ4.tearDown ###Ending testAddDelete
[junit4] 2> 443508 T384 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 443511 T384 C42 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 443512 T384 C42 oass.SolrIndexSearcher.<init> Opening Searcher@5643afd6[collection1] main
[junit4] 2> 443514 T384 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 443514 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5643afd6[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 443515 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 443515 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5643afd6[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 443515 T384 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 7
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrSchemalessExampleTest -Dtests.method=testAddDelete -Dtests.seed=50B839B6A66F747E -Dtests.slow=true -Dtests.locale=iw_IL -Dtests.timezone=ART -Dtests.file.encoding=UTF-8
[junit4] ERROR 200s | SolrSchemalessExampleTest.testAddDelete <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:57127/solr/collection1
[junit4] > at __randomizedtesting.SeedInfo.seed([50B839B6A66F747E:985844BC57C7A7A8]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:563)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
[junit4] > at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
[junit4] > at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
[junit4] > at org.apache.solr.client.solrj.SolrExampleTestsBase.testAddDelete(SolrExampleTestsBase.java:186)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
[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:271)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448)
[junit4] > ... 46 more
[junit4] 2> 443705 T378 oas.SolrTestCaseJ4.setUp ###Starting testArbitraryJsonIndexing
[junit4] 2> 443741 T387 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1479694780638167040)} 0 1
[junit4] 2> 443748 T385 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 443749 T385 C42 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 443750 T385 C42 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 443750 T385 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 443753 T385 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 6
[junit4] 2> 443760 T382 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 443787 T383 C42 oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 443798 T383 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update/json/docs params={} {add=[abc1 (1479694780683255808), c9cb7354-3a73-4200-9cf7-c85632cabf7b (1479694780698984448)]} 0 33
[junit4] 2> 443879 T387 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 443893 T387 C42 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_5,generation=5}
[junit4] 2> 443893 T387 C42 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 443963 T387 C42 oass.SolrIndexSearcher.<init> Opening Searcher@75693c31[collection1] main
[junit4] 2> 443964 T387 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 443965 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@75693c31[collection1] main{UninvertingDirectoryReader(Uninverting(_2(5.0.0):C2))}
[junit4] 2> 443965 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 443965 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@75693c31[collection1] main{UninvertingDirectoryReader(Uninverting(_2(5.0.0):C2))}
[junit4] 2> 443966 T387 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 87
[junit4] 2> 443970 T382 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 443972 T378 oas.SolrTestCaseJ4.tearDown ###Ending testArbitraryJsonIndexing
[junit4] 2> 443985 T378 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnDelete
[junit4] 2> 443990 T383 C42 oass.SolrIndexSearcher.<init> Opening Searcher@7b0cd8c3[collection1] realtime
[junit4] 2> 443990 T383 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1479694780898213888)} 0 2
[junit4] 2> 444005 T384 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 444009 T384 C42 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_6,generation=6}
[junit4] 2> 444009 T384 C42 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 444010 T384 C42 oass.SolrIndexSearcher.<init> Opening Searcher@17928250[collection1] main
[junit4] 2> 444011 T384 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 444012 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@17928250[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 444012 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 444013 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17928250[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 444014 T384 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 9
[junit4] 2> 444019 T386 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 444033 T387 C42 oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 444041 T387 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1479694780939108352)]} 0 14
[junit4] 2> 444047 T385 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 444051 T385 C42 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_7,generation=7}
[junit4] 2> 444052 T385 C42 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 444054 T385 C42 oass.SolrIndexSearcher.<init> Opening Searcher@71f3c132[collection1] main
[junit4] 2> 444054 T385 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 444054 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@71f3c132[collection1] main{UninvertingDirectoryReader(Uninverting(_3(5.0.0):C1))}
[junit4] 2> 444055 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 444056 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71f3c132[collection1] main{UninvertingDirectoryReader(Uninverting(_3(5.0.0):C1))}
[junit4] 2> 444056 T385 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 9
[junit4] 2> 444060 T382 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 444074 T383 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1479694780986294272)]} 0 2
[junit4] 2> 444079 T384 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4] 2> 444337 T386 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 2> 444593 T387 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 444849 T382 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 2> 445075 T392 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 445076 T392 oass.SolrIndexSearcher.<init> Opening Searcher@567219e6[collection1] main
[junit4] 2> 445077 T392 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 445077 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@567219e6[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 445078 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 445078 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@567219e6[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 445105 T383 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 445106 T378 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnDelete
[junit4] 2> 445120 T378 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnAdd
[junit4] 2> 445128 T386 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1479694782092541952)} 0 2
[junit4] 2> 445136 T385 C42 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 445137 T385 C42 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_8,generation=8}
[junit4] 2> 445137 T385 C42 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 445138 T385 C42 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 445138 T385 C42 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 445138 T385 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 2
[junit4] 2> 445144 T387 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2
[junit4] 2> 445150 T382 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1479694782114562048)]} 0 2
[junit4] 2> 445154 T384 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 445651 T392 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 445655 T392 oass.SolrIndexSearcher.<init> Opening Searcher@12a301c1[collection1] main
[junit4] 2> 445656 T392 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 445657 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12a301c1[collection1] main{UninvertingDirectoryReader(Uninverting(_4(5.0.0):C1))}
[junit4] 2> 445657 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 445657 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12a301c1[collection1] main{UninvertingDirectoryReader(Uninverting(_4(5.0.0):C1))}
[junit4] 2> 446159 T385 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 2> 446165 T387 C42 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1479694783179915264)]} 0 1
[junit4] 2> 446666 T392 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 446670 T392 oass.SolrIndexSearcher.<init> Opening Searcher@3bfb6550[collection1] main
[junit4] 2> 446671 T392 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 446671 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3bfb6550[collection1] main{UninvertingDirectoryReader(Uninverting(_4(5.0.0):C1) Uninverting(_5(5.0.0):C1))}
[junit4] 2> 446672 T389 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 446672 T389 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3bfb6550[collection1] main{UninvertingDirectoryReader(Uninverting(_4(5.0.0):C1) Uninverting(_5(5.0.0):C1))}
[junit4] 2> 447173 T383 C42 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 2> 447174 T378 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnAdd
[junit4] 2> 447191 T378 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1912704909
[junit4] 2> 447192 T378 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@78317de6
[junit4] 2> 447194 T378 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=13,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=16,cumulative_deletesById=2,cumulative_deletesByQuery=5,cumulative_errors=0,transaction_logs_total_size=1280,transaction_logs_total_number=9}
[junit4] 2> 447195 T378 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 447195 T378 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 447196 T378 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 447196 T378 C42 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close.
[junit4] 2> 447198 T378 C42 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11fc8143)),segFN=segments_9,generation=9}
[junit4] 2> 447199 T378 C42 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 447206 T378 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 447206 T378 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 447207 T378 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002;done=false>>]
[junit4] 2> 447207 T378 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002
[junit4] 2> 447208 T378 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002/index;done=false>>]
[junit4] 2> 447208 T378 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001/tempDir-002/index
[junit4] 2> 447209 T378 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 452181 T378 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrSchemalessExampleTest-50B839B6A66F747E-001
[junit4] 2> NOTE: test params are: codec=Lucene410: {}, docValues:{}, sim=DefaultSimilarity, locale=iw_IL, timezone=ART
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_20 (64-bit)/cpus=2,threads=1,free=76499328,total=227540992
[junit4] 2> NOTE: All tests run in this JVM: [TestNamedListCodec, TestXMLEscaping, LargeVolumeJettyTest, TermsResponseTest, TestLBHttpSolrServer, SolrExampleXMLTest, SolrParamTest, TestRetryUtil, BasicHttpSolrServerTest, SolrDocumentTest, ConcurrentUpdateSolrServerTest, MultiCoreExampleJettyTest, TestJavaBinCodec, FieldAnalysisResponseTest, TestJsonRecordReader, TestBatchUpdate, TestDocumentObjectBinder, SolrExampleStreamingTest, DocumentAnalysisResponseTest, SolrExceptionTest, NoOpResponseParserTest, MergeIndexesEmbeddedTest, SolrSchemalessExampleTest]
[junit4] Completed in 213.24s, 5 tests, 1 error <<< FAILURES!
[...truncated 97 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:491: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:471: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:60: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:202: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1358: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:961: There were test failures: 53 suites, 313 tests, 1 error
Total time: 158 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any