You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/07/24 09:52:59 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 2035 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2035/
1 tests failed.
FAILED: org.apache.solr.client.solrj.TestLBHttpSolrClient.testTwoServers
Error Message:
Error from server at http://127.0.0.1:46115/solr/collection1: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 500 </title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /solr/collection1/select. Reason: <pre> java.lang.AssertionError</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46115/solr/collection1: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 500 </title>
</head>
<body>
<h2>HTTP ERROR: 500</h2>
<p>Problem accessing /solr/collection1/select. Reason:
<pre> java.lang.AssertionError</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([872443BC748266E6:27CEED31AFC948C6]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:252)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:663)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:638)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
at org.apache.solr.client.solrj.TestLBHttpSolrClient.testTwoServers(TestLBHttpSolrClient.java:191)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13779 lines...]
[junit4] Suite: org.apache.solr.client.solrj.TestLBHttpSolrClient
[junit4] 2> 35429 INFO (SUITE-TestLBHttpSolrClient-seed#[872443BC748266E6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/init-core-data-001
[junit4] 2> 35430 WARN (SUITE-TestLBHttpSolrClient-seed#[872443BC748266E6]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
[junit4] 2> 35432 INFO (SUITE-TestLBHttpSolrClient-seed#[872443BC748266E6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 35433 INFO (SUITE-TestLBHttpSolrClient-seed#[872443BC748266E6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 35437 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSimple
[junit4] 2> 35443 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 35446 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5becef6e{/solr,null,AVAILABLE}
[junit4] 2> 35447 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6d9c3dc3{HTTP/1.1,[http/1.1]}{127.0.0.1:36453}
[junit4] 2> 35447 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @37769ms
[junit4] 2> 35447 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=36453}
[junit4] 2> 35447 ERROR (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 35447 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 35447 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 35447 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 35447 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:33:56.822Z
[junit4] 2> 35452 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/solr.xml
[junit4] 2> 35459 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 35509 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 35535 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 35535 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 35539 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/.
[junit4] 2> 35540 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 35678 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 35710 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 35712 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 35712 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/./collection1, trusted=true
[junit4] 2> 35712 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 35714 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 35714 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/./collection1/data/]
[junit4] 2> 35821 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 35821 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 35833 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f7eeadd[collection1] main]
[junit4] 2> 35834 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/collection1/conf
[junit4] 2> 35834 INFO (coreLoadExecutor-173-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 35836 INFO (searcherExecutor-174-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f7eeadd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 36229 INFO (qtp562480923-416) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 3
[junit4] 2> 36231 INFO (qtp562480923-416) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 36231 INFO (qtp562480923-416) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1aacb299 commitCommandVersion:0
[junit4] 2> 36263 INFO (qtp562480923-416) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@142c7c6[collection1] main]
[junit4] 2> 36263 INFO (qtp562480923-416) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 36265 INFO (searcherExecutor-174-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@142c7c6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 36269 INFO (qtp562480923-416) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 37
[junit4] 2> 36272 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 36273 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f1c12f{/solr,null,AVAILABLE}
[junit4] 2> 36273 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@229c9af1{HTTP/1.1,[http/1.1]}{127.0.0.1:41412}
[junit4] 2> 36273 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @38595ms
[junit4] 2> 36273 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=41412}
[junit4] 2> 36274 ERROR (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 36274 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 36274 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 36274 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 36274 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:33:57.649Z
[junit4] 2> 36274 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/solr.xml
[junit4] 2> 36278 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 36317 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 36324 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 36325 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 36326 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/.
[junit4] 2> 36326 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 36364 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 36375 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 36377 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 36377 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1, trusted=true
[junit4] 2> 36377 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 36377 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 36377 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1/data/]
[junit4] 2> 36444 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 36444 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 36446 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@192ad39c[collection1] main]
[junit4] 2> 36446 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/conf
[junit4] 2> 36447 INFO (coreLoadExecutor-183-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 36449 INFO (searcherExecutor-184-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@192ad39c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 36894 INFO (qtp1114526392-434) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 3
[junit4] 2> 36895 INFO (qtp1114526392-433) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 36895 INFO (qtp1114526392-433) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b169ce7 commitCommandVersion:0
[junit4] 2> 36934 INFO (qtp1114526392-433) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@22cff2b5[collection1] main]
[junit4] 2> 36935 INFO (searcherExecutor-184-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@22cff2b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 36935 INFO (qtp1114526392-433) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 36935 INFO (qtp1114526392-433) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 40
[junit4] 2> 36938 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 36939 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@23c560d8{/solr,null,AVAILABLE}
[junit4] 2> 36939 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@17065d1d{HTTP/1.1,[http/1.1]}{127.0.0.1:52288}
[junit4] 2> 36939 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @39261ms
[junit4] 2> 36939 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=52288}
[junit4] 2> 36939 ERROR (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 36940 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 36940 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 36940 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 36940 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:33:58.315Z
[junit4] 2> 36940 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/solr.xml
[junit4] 2> 36944 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 36969 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 36973 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 36973 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 36975 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/.
[junit4] 2> 36975 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 37000 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 37010 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 37013 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 37013 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/./collection1, trusted=true
[junit4] 2> 37014 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 37014 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 37014 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/./collection1/data/]
[junit4] 2> 37064 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 37064 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 37066 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d4b087a[collection1] main]
[junit4] 2> 37067 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/collection1/conf
[junit4] 2> 37067 INFO (coreLoadExecutor-193-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 37068 INFO (searcherExecutor-194-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7d4b087a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 37533 INFO (qtp869926074-450) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 3
[junit4] 2> 37538 INFO (qtp869926074-450) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 37538 INFO (qtp869926074-450) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@bb8d9b0 commitCommandVersion:0
[junit4] 2> 37565 INFO (qtp869926074-450) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@727549ae[collection1] main]
[junit4] 2> 37565 INFO (qtp869926074-450) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 37569 INFO (searcherExecutor-194-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@727549ae[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 37569 INFO (qtp869926074-450) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 31
[junit4] 2> 37579 INFO (qtp562480923-417) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=8
[junit4] 2> 37583 INFO (qtp1114526392-435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 37586 INFO (qtp869926074-451) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 37589 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@229c9af1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 37589 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1098475562
[junit4] 2> 37590 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 37590 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 37590 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 37590 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 37600 INFO (coreCloseExecutor-198-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7c6f9dc0
[junit4] 2> 37606 INFO (coreCloseExecutor-198-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2087689664
[junit4] 2> 37647 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@f1c12f{/solr,null,UNAVAILABLE}
[junit4] 2> 37649 INFO (qtp562480923-418) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 37654 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.h.i.e.RetryExec I/O exception (org.apache.http.NoHttpResponseException) caught when processing request to {}->http://127.0.0.1:41412: The target server failed to respond
[junit4] 2> 37654 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.h.i.e.RetryExec Retrying request to {}->http://127.0.0.1:41412
[junit4] 2> 37661 INFO (qtp869926074-452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 37686 INFO (qtp562480923-419) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 37687 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 37694 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d59b59f{/solr,null,AVAILABLE}
[junit4] 2> 37694 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2add58e{HTTP/1.1,[http/1.1]}{127.0.0.1:41412}
[junit4] 2> 37694 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @40016ms
[junit4] 2> 37694 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=41412}
[junit4] 2> 37695 ERROR (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 37695 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 37695 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 37695 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 37695 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:33:59.070Z
[junit4] 2> 37695 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/solr.xml
[junit4] 2> 37700 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 37733 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 37737 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 37737 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 37739 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/.
[junit4] 2> 37739 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 37756 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 37777 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 37779 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 37779 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1, trusted=true
[junit4] 2> 37779 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 37780 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 37780 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1/data/]
[junit4] 2> 37816 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 37816 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 37820 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@19def34b[collection1] main]
[junit4] 2> 37820 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/conf
[junit4] 2> 37820 INFO (coreLoadExecutor-205-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 37824 INFO (searcherExecutor-206-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19def34b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 38162 INFO (qtp23923796-471) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&distrib=false&sort=_docid_+asc&rows=0&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 39483 INFO (qtp869926074-453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 39486 INFO (qtp23923796-472) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 39489 INFO (qtp562480923-420) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 39495 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6d9c3dc3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 39495 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1145144591
[junit4] 2> 39495 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 39497 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 39498 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 39498 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 39499 INFO (coreCloseExecutor-210-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4ef10f6b
[junit4] 2> 39502 INFO (coreCloseExecutor-210-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1324420971
[junit4] 2> 39520 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5becef6e{/solr,null,UNAVAILABLE}
[junit4] 2> 39528 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2add58e{HTTP/1.1,[http/1.1]}{127.0.0.1:41412}
[junit4] 2> 39528 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1993314435
[junit4] 2> 39529 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 39529 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 39529 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 39529 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 39532 INFO (coreCloseExecutor-212-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@623c4ceb
[junit4] 2> 39536 INFO (coreCloseExecutor-212-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1648119019
[junit4] 2> 39546 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6d59b59f{/solr,null,UNAVAILABLE}
[junit4] 2> 39552 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@17065d1d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 39552 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1521242827
[junit4] 2> 39556 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 39556 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 39556 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 39558 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 39561 INFO (coreCloseExecutor-214-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@66cb577f
[junit4] 2> 39563 INFO (coreCloseExecutor-214-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1724602239
[junit4] 2> 39592 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@23c560d8{/solr,null,UNAVAILABLE}
[junit4] 2> 39594 INFO (TEST-TestLBHttpSolrClient.testSimple-seed#[872443BC748266E6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSimple
[junit4] 2> 39596 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReliability
[junit4] 2> 39599 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 39601 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@572884f8{/solr,null,AVAILABLE}
[junit4] 2> 39601 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@611c8785{HTTP/1.1,[http/1.1]}{127.0.0.1:51961}
[junit4] 2> 39601 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @41923ms
[junit4] 2> 39601 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=51961}
[junit4] 2> 39602 ERROR (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 39602 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 39602 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 39602 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 39602 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:34:00.977Z
[junit4] 2> 39603 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/solr.xml
[junit4] 2> 39611 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 39643 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 39647 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 39647 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 39648 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/.
[junit4] 2> 39648 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 39659 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 39667 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 39671 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 39671 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/./collection1, trusted=true
[junit4] 2> 39672 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 39672 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 39672 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/./collection1/data/]
[junit4] 2> 39818 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 39818 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 39828 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c2fcf5e[collection1] main]
[junit4] 2> 39829 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/collection1/conf
[junit4] 2> 39829 INFO (coreLoadExecutor-221-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 39831 INFO (searcherExecutor-222-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c2fcf5e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 40170 INFO (qtp919096456-498) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 2
[junit4] 2> 40172 INFO (qtp919096456-499) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 40172 INFO (qtp919096456-499) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@25967771 commitCommandVersion:0
[junit4] 2> 40205 INFO (qtp919096456-499) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@43895ee3[collection1] main]
[junit4] 2> 40205 INFO (qtp919096456-499) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 40206 INFO (searcherExecutor-222-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43895ee3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 40206 INFO (qtp919096456-499) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 34
[junit4] 2> 40209 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 40230 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45ddb038{/solr,null,AVAILABLE}
[junit4] 2> 40230 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@69da470e{HTTP/1.1,[http/1.1]}{127.0.0.1:39588}
[junit4] 2> 40231 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @42553ms
[junit4] 2> 40231 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=39588}
[junit4] 2> 40231 ERROR (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 40231 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 40231 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 40231 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 40231 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:34:01.606Z
[junit4] 2> 40232 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/solr.xml
[junit4] 2> 40243 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 40273 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40277 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40277 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40279 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/.
[junit4] 2> 40280 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 40321 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 40340 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 40342 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 40342 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1, trusted=true
[junit4] 2> 40343 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40343 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 40343 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1/data/]
[junit4] 2> 40437 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 40437 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 40439 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7886fffa[collection1] main]
[junit4] 2> 40440 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/conf
[junit4] 2> 40440 INFO (coreLoadExecutor-231-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 40442 INFO (searcherExecutor-232-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7886fffa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 40867 INFO (qtp1344201310-516) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 13
[junit4] 2> 40869 INFO (qtp1344201310-513) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 40869 INFO (qtp1344201310-513) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@315f4039 commitCommandVersion:0
[junit4] 2> 40910 INFO (qtp1344201310-513) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6b43df7f[collection1] main]
[junit4] 2> 40911 INFO (searcherExecutor-232-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6b43df7f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 40911 INFO (qtp1344201310-513) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 40911 INFO (qtp1344201310-513) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 42
[junit4] 2> 40915 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 40917 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27a92b91{/solr,null,AVAILABLE}
[junit4] 2> 40917 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@418ce13f{HTTP/1.1,[http/1.1]}{127.0.0.1:35732}
[junit4] 2> 40918 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @43240ms
[junit4] 2> 40918 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=35732}
[junit4] 2> 40919 ERROR (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 40919 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 40919 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 40919 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 40919 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:34:02.294Z
[junit4] 2> 40919 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/solr.xml
[junit4] 2> 40924 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 40949 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40953 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40953 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40955 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/.
[junit4] 2> 40955 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 40969 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 40977 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 40980 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 40980 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/./collection1, trusted=true
[junit4] 2> 40980 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 40980 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 40980 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/./collection1/data/]
[junit4] 2> 41025 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 41025 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 41027 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@52c19b08[collection1] main]
[junit4] 2> 41028 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-2-001/collection1/conf
[junit4] 2> 41028 INFO (coreLoadExecutor-241-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 41031 INFO (searcherExecutor-242-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@52c19b08[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 41489 INFO (qtp1603857111-532) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 2
[junit4] 2> 41491 INFO (qtp1603857111-533) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 41491 INFO (qtp1603857111-533) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@44443b7f commitCommandVersion:0
[junit4] 2> 41512 INFO (qtp1603857111-533) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a7d113a[collection1] main]
[junit4] 2> 41512 INFO (qtp1603857111-533) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 41513 INFO (searcherExecutor-242-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a7d113a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 41513 INFO (qtp1603857111-533) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 22
[junit4] 2> 41517 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@69da470e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 41517 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=972061252
[junit4] 2> 41517 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 41517 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 41518 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 41518 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 41518 INFO (coreCloseExecutor-246-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@229ede34
[junit4] 2> 41521 INFO (coreCloseExecutor-246-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=580836916
[junit4] 2> 41525 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@45ddb038{/solr,null,UNAVAILABLE}
[junit4] 2> 41527 INFO (qtp919096456-496) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 41532 INFO (qtp1603857111-535) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 41534 INFO (qtp1603857111-536) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 41536 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 41540 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e9848a4{/solr,null,AVAILABLE}
[junit4] 2> 41540 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@709840e{HTTP/1.1,[http/1.1]}{127.0.0.1:39588}
[junit4] 2> 41540 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @43862ms
[junit4] 2> 41540 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=39588}
[junit4] 2> 41542 ERROR (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 41542 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 41542 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 41542 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 41542 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:34:02.917Z
[junit4] 2> 41542 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/solr.xml
[junit4] 2> 41547 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 41583 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 41588 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 41588 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 41590 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/.
[junit4] 2> 41590 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 41601 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 41611 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 41620 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 41620 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1, trusted=true
[junit4] 2> 41621 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 41621 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 41621 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/./collection1/data/]
[junit4] 2> 41657 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 41657 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 41662 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a5f2b84[collection1] main]
[junit4] 2> 41662 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-1-001/collection1/conf
[junit4] 2> 41663 INFO (coreLoadExecutor-253-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 41664 INFO (searcherExecutor-254-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3a5f2b84[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c10)))}
[junit4] 2> 42044 INFO (qtp1884382900-555) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&distrib=false&sort=_docid_+asc&rows=0&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 2> 42133 INFO (qtp1603857111-529) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 2> 42636 INFO (qtp1884382900-557) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 42660 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@611c8785{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 42660 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1684224474
[junit4] 2> 42660 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 42662 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 42663 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 42666 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 42690 INFO (coreCloseExecutor-258-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7fab2ae8
[junit4] 2> 42706 INFO (coreCloseExecutor-258-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2141924072
[junit4] 2> 42722 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@572884f8{/solr,null,UNAVAILABLE}
[junit4] 2> 42738 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@709840e{HTTP/1.1,[http/1.1]}{127.0.0.1:39588}
[junit4] 2> 42738 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1556124483
[junit4] 2> 42738 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 42738 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 42739 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 42739 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 42756 INFO (coreCloseExecutor-260-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5dda5334
[junit4] 2> 42788 INFO (coreCloseExecutor-260-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1574589236
[junit4] 2> 42812 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6e9848a4{/solr,null,UNAVAILABLE}
[junit4] 2> 42828 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@418ce13f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 42828 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=784779267
[junit4] 2> 42829 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 42829 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 42829 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 42829 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 42836 INFO (coreCloseExecutor-262-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1eb22880
[junit4] 2> 42852 INFO (coreCloseExecutor-262-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=514992256
[junit4] 2> 42872 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@27a92b91{/solr,null,UNAVAILABLE}
[junit4] 2> 42874 INFO (TEST-TestLBHttpSolrClient.testReliability-seed#[872443BC748266E6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReliability
[junit4] 2> 42876 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTwoServers
[junit4] 2> 42892 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 42992 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6665fff{/solr,null,AVAILABLE}
[junit4] 2> 42993 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a107257{HTTP/1.1,[http/1.1]}{127.0.0.1:46115}
[junit4] 2> 42993 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.Server Started @45315ms
[junit4] 2> 42993 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=46115}
[junit4] 2> 42993 ERROR (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 42993 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 42993 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 42993 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 42993 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-24T09:34:04.368Z
[junit4] 2> 42994 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001/instance-0-001/solr.xml
[junit4] 2> 42998 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 43021 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 43024 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18741f82
[junit4] 2> 43024 INFO (TEST-TestLBHttpSolrClient.testTwoServers-se
[...truncated too long message...]
.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:322)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 48484 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3638d6a3{HTTP/1.1,[http/1.1]}{127.0.0.1:46115}
[junit4] 2> 48484 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=688243321
[junit4] 2> 48484 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 48486 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 48486 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 48492 ERROR (coreCloseExecutor-312-thread-1) [ ] o.a.s.c.SolrCore Too many close [count:-1] on org.apache.solr.core.SolrCore@b7a74a2. Please report this exception to solr-user@lucene.apache.org
[junit4] 2> 48492 ERROR (coreCloseExecutor-312-thread-1) [ ] o.a.s.c.SolrCores Error shutting down core:java.lang.AssertionError: Too many closes on SolrCore
[junit4] 2> at org.apache.solr.core.SolrCore.close(SolrCore.java:1477)
[junit4] 2> at org.apache.solr.core.SolrCores.lambda$close$0(SolrCores.java:141)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 48493 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@50bcb96c{/solr,null,UNAVAILABLE}
[junit4] 2> 48557 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@44cf934a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 48557 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1333602319
[junit4] 2> 48557 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 48557 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 48558 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 48558 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 48564 INFO (coreCloseExecutor-314-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9a675
[junit4] 2> 48588 INFO (coreCloseExecutor-314-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=632437
[junit4] 2> 48605 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@32844763{/solr,null,UNAVAILABLE}
[junit4] 2> 48614 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[872443BC748266E6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTwoServers
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLBHttpSolrClient -Dtests.method=testTwoServers -Dtests.seed=872443BC748266E6 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ja-JP -Dtests.timezone=America/Guayaquil -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 5.89s J0 | TestLBHttpSolrClient.testTwoServers <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46115/solr/collection1: Expected mime type application/octet-stream but got text/html. <html>
[junit4] > <head>
[junit4] > <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
[junit4] > <title>Error 500 </title>
[junit4] > </head>
[junit4] > <body>
[junit4] > <h2>HTTP ERROR: 500</h2>
[junit4] > <p>Problem accessing /solr/collection1/select. Reason:
[junit4] > <pre> java.lang.AssertionError</pre></p>
[junit4] > <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
[junit4] > </body>
[junit4] > </html>
[junit4] > at __randomizedtesting.SeedInfo.seed([872443BC748266E6:27CEED31AFC948C6]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:252)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:663)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:638)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
[junit4] > at org.apache.solr.client.solrj.TestLBHttpSolrClient.testTwoServers(TestLBHttpSolrClient.java:191)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_872443BC748266E6-001
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=9, maxDocsPerChunk=4, blockSize=7), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=9, blockSize=7)), sim=RandomSimilarity(queryNorm=true): {}, locale=ja-JP, timezone=America/Guayaquil
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=2,free=204188400,total=353894400
[junit4] 2> NOTE: All tests run in this JVM: [HyperbolicSineEvaluatorTest, UuidEvaluatorTest, ConcurrentUpdateSolrClientTest, TestJsonRecordReader, LargeVolumeBinaryJettyTest, ExclusiveOrEvaluatorTest, TestSuggesterResponse, TestNamedListCodec, JDBCStreamTest, TestEmbeddedSolrServer, LargeVolumeEmbeddedTest, AnlysisResponseBaseTest, HttpSolrClientSSLAuthConPoolTest, AbsoluteValueEvaluatorTest, TestLBHttpSolrClient]
[junit4] Completed [51/135 (1!)] on J0 in 13.39s, 3 tests, 1 error <<< FAILURES!
[...truncated 42638 lines...]
[JENKINS] Lucene-Solr-Tests-master - Build # 2037 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2037/
1 tests failed.
FAILED: org.apache.solr.update.HardAutoCommitTest.testCommitWithin
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([894EFB234C1AFE08:339C945BCF34101D]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886)
at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
</response>
request was:q=id:529&qt=&start=0&rows=20&version=2.2
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879)
... 40 more
Build Log:
[...truncated 11612 lines...]
[junit4] Suite: org.apache.solr.update.HardAutoCommitTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_894EFB234C1AFE08-001/init-core-data-001
[junit4] 2> 902201 WARN (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 902201 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 902203 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 902203 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 902204 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 902204 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 902238 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 902264 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 902385 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 902416 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d48fbab
[junit4] 2> 902420 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d48fbab
[junit4] 2> 902420 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d48fbab
[junit4] 2> 902421 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 902446 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 902464 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 902548 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 902554 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 902554 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d48fbab
[junit4] 2> 902554 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 902554 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_894EFB234C1AFE08-001/init-core-data-001/]
[junit4] 2> 902556 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=22.572265625, floorSegmentMB=0.8623046875, forceMergeDeletesPctAllowed=18.43710044698169, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 902597 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 902597 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 902598 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 902598 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 902599 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=25.4169921875, floorSegmentMB=1.0146484375, forceMergeDeletesPctAllowed=3.1642915155203344, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 902599 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d04aa80[collection1] main]
[junit4] 2> 902600 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 902605 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 902630 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 902631 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 902631 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 902631 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 902631 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 902631 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 902632 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 902632 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 902632 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 902633 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 902634 INFO (searcherExecutor-3287-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7d04aa80[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 902635 INFO (coreLoadExecutor-3286-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1573823184546824192
[junit4] 2> 902639 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 902641 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
[junit4] 2> 902642 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 902642 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@162afe0[collection1] realtime]
[junit4] 2> 902643 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
[junit4] 2> 902643 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 902644 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 902676 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 902694 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 902819 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 902825 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
[junit4] 2> 902839 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d48fbab
[junit4] 2> 902839 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_894EFB234C1AFE08-001/init-core-data-001/]
[junit4] 2> 902883 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 902883 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 902884 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@703a8a8c[collection1] main]
[junit4] 2> 902885 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 902886 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 902888 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 902888 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1573823184812113920
[junit4] 2> 902891 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 902891 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 902891 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 902891 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 902891 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 902891 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 902891 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 902892 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 902892 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 902900 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 902900 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 902900 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@703a8a8c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 902901 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@26edb4f1[collection1] main]
[junit4] 2> 902901 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@31ff4cf9
[junit4] 2> 902901 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=838814969
[junit4] 2> 902911 INFO (searcherExecutor-3292-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26edb4f1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 902913 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1573823184835182592)]} 0 6
[junit4] 2> 902914 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 902927 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1573823184842522624)]} 0 19
[junit4] 2> 902927 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 902928 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1573823184854056960)]} 0 21
[junit4] 2> 902929 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 902929 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=&start=0&rows=20&version=2.2
[junit4] 2> 902929 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:529&qt=&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=&start=0&rows=20&version=2.2
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879)
[junit4] 2> at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 902930 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[894EFB234C1AFE08]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HardAutoCommitTest -Dtests.method=testCommitWithin -Dtests.seed=894EFB234C1AFE08 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=hi -Dtests.timezone=Pacific/Truk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.29s J2 | HardAutoCommitTest.testCommitWithin <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([894EFB234C1AFE08:339C945BCF34101D]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886)
[junit4] > at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] > </response>
[junit4] > request was:q=id:529&qt=&start=0&rows=20&version=2.2
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879)
[junit4] > ... 40 more
[junit4] 2> 902932 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 902932 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1118337233
[junit4] 2> 902932 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 902933 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 902934 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 902939 INFO (SUITE-HardAutoCommitTest-seed#[894EFB234C1AFE08]-worker) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 902939 INFO (coreCloseExecutor-3296-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@537c83fd
[junit4] 2> 902940 INFO (coreCloseExecutor-3296-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 902940 INFO (coreCloseExecutor-3296-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7657bbed commitCommandVersion:0
[junit4] 2> 902967 INFO (coreCloseExecutor-3296-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1400669181
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_894EFB234C1AFE08-001
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=4, maxDocsPerChunk=6, blockSize=3), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=4, blockSize=3)), sim=RandomSimilarity(queryNorm=true): {}, locale=hi, timezone=Pacific/Truk
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=1,free=151508864,total=521666560
[junit4] 2> NOTE: All tests run in this JVM: [TestTrackingShardHandlerFactory, TestSolr4Spatial2, TestFastWriter, AnalyticsQueryTest, DateMathParserTest, PeerSyncReplicationTest, TestSolrConfigHandlerConcurrent, SuggesterFSTTest, RecoveryZkTest, DistribCursorPagingTest, TestGroupingSearch, BasicAuthStandaloneTest, TestQueryUtils, SearchHandlerTest, BasicDistributedZk2Test, TestBackupRepositoryFactory, TestExactStatsCache, TestReloadAndDeleteDocs, SolrIndexMetricsTest, ConfigureRecoveryStrategyTest, TestBulkSchemaAPI, TestDistributedMissingSort, TestFieldTypeCollectionResource, TestDFRSimilarityFactory, HdfsRecoverLeaseTest, V2StandaloneTest, ManagedSchemaRoundRobinCloudTest, OverseerRolesTest, ConnectionManagerTest, TestInfoStreamLogging, TestSSLRandomization, MetricsConfigTest, ReturnFieldsTest, TestAnalyzedSuggestions, SimplePostToolTest, ClusterStateTest, StatsComponentTest, OverriddenZkACLAndCredentialsProvidersTest, NumericFieldsTest, TestCodecSupport, QueryEqualityTest, TestFieldCache, TestReversedWildcardFilterFactory, DeleteInactiveReplicaTest, SolrCoreCheckLockOnStartupTest, CleanupOldIndexTest, TestReplicationHandlerBackup, TestXmlQParser, TestPseudoReturnFields, SolrCloudReportersTest, SolrTestCaseJ4Test, TestReload, FastVectorHighlighterTest, TestRTimerTree, TestMissingGroups, ReplicaListTransformerTest, TestSQLHandler, CollectionStateFormat2Test, TestLMJelinekMercerSimilarityFactory, SimpleFacetsTest, FullSolrCloudDistribCmdsTest, XsltUpdateRequestHandlerTest, TestCloudSchemaless, TestCloudInspectUtil, BaseCdcrDistributedZkTest, TestLockTree, TestConfigSetProperties, LeaderElectionTest, SpellCheckCollatorWithCollapseTest, DeleteReplicaTest, DistributedFacetPivotLongTailTest, TestSchemaResource, TestRawTransformer, TestManagedResource, TestConfigsApi, PropertiesRequestHandlerTest, StatsReloadRaceTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, HdfsChaosMonkeySafeLeaderTest, TestClusterStateMutator, SimpleMLTQParserTest, DistributedSuggestComponentTest, TestFileDictionaryLookup, TestTolerantSearch, TestReplicaProperties, TestJavabinTupleStreamParser, TestBlobHandler, TestCloudPseudoReturnFields, TestLFUCache, SimpleCollectionCreateDeleteTest, TestManagedSynonymFilterFactory, LeaderFailoverAfterPartitionTest, TestDelegationWithHadoopAuth, MoveReplicaHDFSTest, LeaderInitiatedRecoveryOnShardRestartTest, CoreMergeIndexesAdminHandlerTest, HardAutoCommitTest]
[junit4] Completed [277/725 (1!)] on J2 in 0.79s, 1 test, 1 error <<< FAILURES!
[...truncated 44783 lines...]
[JENKINS] Lucene-Solr-Tests-master - Build # 2036 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2036/
1 tests failed.
FAILED: org.apache.solr.update.HardAutoCommitTest.testCommitWithin
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([94DB7A043F8BC34A:2E09157CBCA52D5F]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879)
at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
</response>
request was:q=id:529&qt=&start=0&rows=20&version=2.2
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:872)
... 40 more
Build Log:
[...truncated 12068 lines...]
[junit4] Suite: org.apache.solr.update.HardAutoCommitTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_94DB7A043F8BC34A-001/init-core-data-001
[junit4] 2> 1122331 WARN (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=487 numCloses=487
[junit4] 2> 1122345 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1122346 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1122346 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1122347 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1122347 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1122383 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1122407 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1122628 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1122656 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63d84186
[junit4] 2> 1122659 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63d84186
[junit4] 2> 1122659 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63d84186
[junit4] 2> 1122660 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1122694 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1122711 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1122820 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1122825 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1122825 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63d84186
[junit4] 2> 1122826 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1122826 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_94DB7A043F8BC34A-001/init-core-data-001/]
[junit4] 2> 1122827 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7607059212894925]
[junit4] 2> 1122863 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1122863 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1122864 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1122864 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1122864 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3057025232835249]
[junit4] 2> 1122865 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@569748df[collection1] main]
[junit4] 2> 1122865 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1122872 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1122893 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1122893 INFO (coreLoadExecutor-4597-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1573808518923812864
[junit4] 2> 1122895 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1122895 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1122895 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1122895 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1122896 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1122897 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1122898 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1122899 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
[junit4] 2> 1122899 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1122899 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1122900 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c413ed8[collection1] realtime]
[junit4] 2> 1122900 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
[junit4] 2> 1122900 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 1122901 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1122901 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1122905 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1122906 INFO (searcherExecutor-4598-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@569748df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1123051 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1123089 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1123201 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1123207 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
[junit4] 2> 1123220 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63d84186
[junit4] 2> 1123220 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_94DB7A043F8BC34A-001/init-core-data-001/]
[junit4] 2> 1123258 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1123258 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1123259 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a51d52f[collection1] main]
[junit4] 2> 1123260 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1123260 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1123262 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1123262 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1573808519310737408
[junit4] 2> 1123264 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1123264 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1123265 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1123265 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1123265 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1123265 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1123266 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=75.7900390625, floorSegmentMB=1.96484375, forceMergeDeletesPctAllowed=16.03690991505753, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5774185221805339
[junit4] 2> 1123266 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1123266 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1123266 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1123266 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1123268 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a51d52f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1123268 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34f02781[collection1] main]
[junit4] 2> 1123269 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@18225519
[junit4] 2> 1123269 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=404903193
[junit4] 2> 1123277 INFO (searcherExecutor-4603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34f02781[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1123278 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1573808519325417472)]} 0 4
[junit4] 2> 1123279 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 1123281 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1573808519330660352)]} 0 6
[junit4] 2> 1123281 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 1123282 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1573808519331708928)]} 0 7
[junit4] 2> 1123282 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 1123282 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=&start=0&rows=20&version=2.2
[junit4] 2> 1123282 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:529&qt=&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=&start=0&rows=20&version=2.2
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:872)
[junit4] 2> at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1123283 INFO (TEST-HardAutoCommitTest.testCommitWithin-seed#[94DB7A043F8BC34A]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HardAutoCommitTest -Dtests.method=testCommitWithin -Dtests.seed=94DB7A043F8BC34A -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=uk -Dtests.timezone=America/El_Salvador -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.39s J2 | HardAutoCommitTest.testCommitWithin <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([94DB7A043F8BC34A:2E09157CBCA52D5F]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879)
[junit4] > at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] > </response>
[junit4] > request was:q=id:529&qt=&start=0&rows=20&version=2.2
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:872)
[junit4] > ... 40 more
[junit4] 2> 1123284 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1123284 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=880788544
[junit4] 2> 1123284 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1123285 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1123286 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1123286 INFO (SUITE-HardAutoCommitTest-seed#[94DB7A043F8BC34A]-worker) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1123287 INFO (coreCloseExecutor-4607-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2e21644d
[junit4] 2> 1123287 INFO (coreCloseExecutor-4607-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 1123287 INFO (coreCloseExecutor-4607-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@15e9f04b commitCommandVersion:0
[junit4] 2> 1123289 INFO (coreCloseExecutor-4607-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=773940301
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_94DB7A043F8BC34A-001
[junit4] 2> Jul 24, 2017 12:52:43 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {field_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=PostingsFormat(name=Direct), subject=PostingsFormat(name=Direct), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Lucene70), intDvoDefault=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=651, maxMBSortInHeap=7.63259062824369, sim=RandomSimilarity(queryNorm=true): {}, locale=uk, timezone=America/El_Salvador
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=1,free=201883696,total=533725184
[junit4] 2> NOTE: All tests run in this JVM: [DateRangeFieldTest, TestDFRSimilarityFactory, TestStressReorder, TestBlobHandler, TestRawTransformer, TermVectorComponentDistributedTest, SolrCloudReportersTest, TestTolerantSearch, SpellPossibilityIteratorTest, TestCollectionAPI, OverseerRolesTest, SimpleCollectionCreateDeleteTest, ConnectionManagerTest, TestMissingGroups, DocumentBuilderTest, DistributedSuggestComponentTest, QueryEqualityTest, TestPseudoReturnFields, NumericFieldsTest, TestQueryUtils, TestReload, DateMathParserTest, SimpleMLTQParserTest, BasicAuthStandaloneTest, MoveReplicaHDFSTest, TestNumericTokenStream, TestMacroExpander, TestSearcherReuse, TestSolr4Spatial2, SolrXmlInZkTest, RequiredFieldsTest, TestJavabinTupleStreamParser, UpdateLogTest, OverriddenZkACLAndCredentialsProvidersTest, MergeStrategyTest, TestJmxIntegration, TestAnalyzedSuggestions, CleanupOldIndexTest, TestStandardQParsers, CdcrReplicationHandlerTest, TestExclusionRuleCollectionAccess, TestSolrConfigHandlerConcurrent, TestScoreJoinQPScore, BasicDistributedZk2Test, TestConfigSetsAPIExclusivity, TestTrackingShardHandlerFactory, FullSolrCloudDistribCmdsTest, TestSQLHandler, BlockCacheTest, ShardSplitTest, SpellCheckCollatorWithCollapseTest, TestBulkSchemaAPI, LeaderFailoverAfterPartitionTest, UnloadDistributedZkTest, TestUnifiedSolrHighlighter, DistributedFacetPivotWhiteBoxTest, TestCloudSchemaless, BlobRepositoryCloudTest, TestConfigSetProperties, TestTrie, LeaderElectionTest, TestFieldCache, DistanceUnitsTest, TimeZoneUtilsTest, TestQueryWrapperFilter, TermsComponentTest, TestClusterStateMutator, UtilsToolTest, ClassificationUpdateProcessorIntegrationTest, ExitableDirectoryReaderTest, SparseHLLTest, CircularListTest, TestFastWriter, TestSortByMinMaxFunction, NotRequiredUniqueKeyTest, BigEndianAscendingWordSerializerTest, CoreMergeIndexesAdminHandlerTest, TestExportWriter, PingRequestHandlerTest, DistributedQueueTest, PrimitiveFieldTypeTest, AliasIntegrationTest, TestBackupRepositoryFactory, URLClassifyProcessorTest, TestInfoStreamLogging, TestLeaderElectionZkExpiry, TestJettySolrRunner, DirectUpdateHandlerTest, TestSolrCloudWithSecureImpersonation, TestInitQParser, SearchHandlerTest, SolrIndexMetricsTest, MultiThreadedOCPTest, UniqFieldsUpdateProcessorFactoryTest, AtomicUpdateProcessorFactoryTest, TestZkChroot, DeleteReplicaTest, TestFoldingMultitermQuery, ClusterStateTest, ShowFileRequestHandlerTest, TestUseDocValuesAsStored, TestSolrConfigHandler, TestUtils, TestReRankQParserPlugin, TestDelegationWithHadoopAuth, TestFieldTypeCollectionResource, TestFieldCacheSortRandom, TestXmlQParser, TestManagedSynonymFilterFactory, TestSort, TestRTimerTree, MinimalSchemaTest, SuggesterFSTTest, TestHdfsUpdateLog, DocExpirationUpdateProcessorFactoryTest, TestCustomStream, DistributedExpandComponentTest, SignatureUpdateProcessorFactoryTest, LeaderElectionIntegrationTest, ZkSolrClientTest, TestRealTimeGet, HardAutoCommitTest]
[junit4] Completed [380/725 (1!)] on J2 in 1.26s, 1 test, 1 error <<< FAILURES!
[...truncated 44324 lines...]