Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2067/
2 tests failed. FAILED: org.apache.solr.cloud.HealthCheckHandlerTest.testHealthCheckHandlerSolrJ Error Message: Error from server at http://127.0.0.1:34199/solr: Host Unavailable: Not connected to zk Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34199/solr: Host Unavailable: Not connected to zk at __randomizedtesting.SeedInfo.seed([3CBFC6A590224A60:964C19B1EBC40261]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:195) at org.apache.solr.cloud.HealthCheckHandlerTest.testHealthCheckHandlerSolrJ(HealthCheckHandlerTest.java:78) 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) FAILED: org.apache.solr.update.AutoCommitTest.testCommitWithin Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query at __randomizedtesting.SeedInfo.seed([3CBFC6A590224A60:866DA9DD130CA475]:0) at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886) at org.apache.solr.update.AutoCommitTest.testCommitWithin(AutoCommitTest.java:353) 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 10953 lines...] [junit4] Suite: org.apache.solr.cloud.HealthCheckHandlerTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.HealthCheckHandlerTest_3CBFC6A590224A60-001/init-core-data-001 [junit4] 2> 276255 WARN (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9 [junit4] 2> 276268 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 276273 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-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> 276273 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 276274 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.HealthCheckHandlerTest_3CBFC6A590224A60-001/tempDir-001 [junit4] 2> 276274 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 276274 INFO (Thread-278) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 276274 INFO (Thread-278) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 276276 ERROR (Thread-278) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 276374 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:38043 [junit4] 2> 276399 INFO (jetty-launcher-180-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 276400 INFO (jetty-launcher-180-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4fe6cf26{/solr,null,AVAILABLE} [junit4] 2> 276401 INFO (jetty-launcher-180-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@51541e95{HTTP/1.1,[http/1.1]}{127.0.0.1:34199} [junit4] 2> 276403 INFO (jetty-launcher-180-thread-1) [ ] o.e.j.s.Server Started @280801ms [junit4] 2> 276403 INFO (jetty-launcher-180-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34199} [junit4] 2> 276403 ERROR (jetty-launcher-180-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 276404 INFO (jetty-launcher-180-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 276404 INFO (jetty-launcher-180-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 276404 INFO (jetty-launcher-180-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 276404 INFO (jetty-launcher-180-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-08T11:53:37.023Z [junit4] 2> 276418 INFO (jetty-launcher-180-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 276432 INFO (jetty-launcher-180-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38043/solr [junit4] 2> 276604 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 276605 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34199_solr [junit4] 2> 276606 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.c.Overseer Overseer (id=98447734662365187-127.0.0.1:34199_solr-n_0000000000) starting [junit4] 2> 276642 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34199_solr [junit4] 2> 276644 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 276785 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34199.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@78dd0deb [junit4] 2> 276790 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34199.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@78dd0deb [junit4] 2> 276790 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34199.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@78dd0deb [junit4] 2> 276791 INFO (jetty-launcher-180-thread-1) [n:127.0.0.1:34199_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.HealthCheckHandlerTest_3CBFC6A590224A60-001/tempDir-001/node1/. [junit4] 2> 276832 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 276833 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38043/solr ready [junit4] 2> 276937 INFO (TEST-HealthCheckHandlerTest.testHealthCheckHandler-seed#[3CBFC6A590224A60]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHealthCheckHandler [junit4] 2> 276953 INFO (qtp1746977397-2208) [n:127.0.0.1:34199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/health params={wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 276954 INFO (TEST-HealthCheckHandlerTest.testHealthCheckHandler-seed#[3CBFC6A590224A60]) [ ] o.a.s.c.MiniSolrCloudCluster Expired zookeeper session 98447734662365187 from node http://127.0.0.1:34199/solr [junit4] 2> 276962 WARN (jetty-launcher-180-thread-1-SendThread(127.0.0.1:38043)) [n:127.0.0.1:34199_solr ] o.a.z.ClientCnxn Session 0x15dc1b16d7e0003 for server 127.0.0.1/127.0.0.1:38043, unexpected error, closing socket connection and attempting reconnect [junit4] 2> java.nio.channels.ClosedChannelException [junit4] 2> at sun.nio.ch.SocketChannelImpl.ensureReadOpen(SocketChannelImpl.java:257) [junit4] 2> at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:300) [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:68) [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:366) [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141) [junit4] 2> 276965 INFO (zkCallback-192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 277056 INFO (qtp1746977397-2206) [n:127.0.0.1:34199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/health params={wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 277057 INFO (TEST-HealthCheckHandlerTest.testHealthCheckHandler-seed#[3CBFC6A590224A60]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHealthCheckHandler [junit4] 2> 277086 WARN (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3bc60196 name: ZooKeeperConnection Watcher:127.0.0.1:38043/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 277090 WARN (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 277132 INFO (TEST-HealthCheckHandlerTest.testHealthCheckHandlerWithCloudClient-seed#[3CBFC6A590224A60]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHealthCheckHandlerWithCloudClient [junit4] 2> 277134 INFO (TEST-HealthCheckHandlerTest.testHealthCheckHandlerWithCloudClient-seed#[3CBFC6A590224A60]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHealthCheckHandlerWithCloudClient [junit4] 2> 277216 INFO (TEST-HealthCheckHandlerTest.testHealthCheckHandlerSolrJ-seed#[3CBFC6A590224A60]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHealthCheckHandlerSolrJ [junit4] 2> 277228 INFO (qtp1746977397-2212) [n:127.0.0.1:34199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/health params={wt=javabin&version=2} status=503 QTime=0 [junit4] 2> 277229 INFO (TEST-HealthCheckHandlerTest.testHealthCheckHandlerSolrJ-seed#[3CBFC6A590224A60]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHealthCheckHandlerSolrJ [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HealthCheckHandlerTest -Dtests.method=testHealthCheckHandlerSolrJ -Dtests.seed=3CBFC6A590224A60 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=Antarctica/South_Pole -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.10s J1 | HealthCheckHandlerTest.testHealthCheckHandlerSolrJ <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34199/solr: Host Unavailable: Not connected to zk [junit4] > at __randomizedtesting.SeedInfo.seed([3CBFC6A590224A60:964C19B1EBC40261]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:195) [junit4] > at org.apache.solr.cloud.HealthCheckHandlerTest.testHealthCheckHandlerSolrJ(HealthCheckHandlerTest.java:78) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 277233 INFO (jetty-closer-181-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@51541e95{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 277236 INFO (jetty-closer-181-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=550348015 [junit4] 2> 277236 INFO (jetty-closer-181-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 277241 INFO (jetty-closer-181-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 277245 INFO (jetty-closer-181-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 278165 WARN (jetty-launcher-180-thread-1-SendThread(127.0.0.1:38043)) [n:127.0.0.1:34199_solr ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x15dc1b16d7e0003 has expired [junit4] 2> 278166 WARN (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3bc60196 name: ZooKeeperConnection Watcher:127.0.0.1:38043/solr got event WatchedEvent state:Expired type:None path:null path: null type: None [junit4] 2> 278166 WARN (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 278166 INFO (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.Overseer Overseer (id=98447734662365187-127.0.0.1:34199_solr-n_0000000000) closing [junit4] 2> 278166 INFO (OverseerStateUpdate-98447734662365187-127.0.0.1:34199_solr-n_0000000000) [n:127.0.0.1:34199_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34199_solr [junit4] 2> 278167 INFO (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 278167 WARN (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> 278187 INFO (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished. [junit4] 2> 278187 INFO (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.ZkController ZooKeeper session re-connected ... refreshing core states after session expiration. [junit4] 2> 278265 ERROR (jetty-closer-181-thread-1) [ ] o.a.s.c.Overseer Could not create Overseer node [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) [junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:392) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:389) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:389) [junit4] 2> at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:758) [junit4] 2> at org.apache.solr.cloud.Overseer.getStateUpdateQueue(Overseer.java:631) [junit4] 2> at org.apache.solr.cloud.Overseer.getStateUpdateQueue(Overseer.java:618) [junit4] 2> at org.apache.solr.cloud.ZkController.publishNodeAsDown(ZkController.java:2515) [junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:725) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.destroy(SolrDispatchFilter.java:313) [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.destroyInstance(FilterHolder.java:172) [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doStop(FilterHolder.java:150) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doStop(ServletHandler.java:238) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160) [junit4] 2> at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doStop(SessionHandler.java:127) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160) [junit4] 2> at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.stopContext(ContextHandler.java:825) [junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.stopContext(ServletContextHandler.java:356) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doStop(ContextHandler.java:880) [junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.doStop(ServletContextHandler.java:272) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160) [junit4] 2> at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142) [junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160) [junit4] 2> at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73) [junit4] 2> at org.eclipse.jetty.server.Server.doStop(Server.java:493) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.stop(JettySolrRunner.java:428) [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.stopJettySolrRunner(MiniSolrCloudCluster.java:421) [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$shutdown$1(MiniSolrCloudCluster.java:458) [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:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 278266 WARN (jetty-closer-181-thread-1) [ ] o.a.s.c.ZkController Could not publish node as down: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer [junit4] 2> 278266 INFO (jetty-closer-181-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 278568 WARN (zkCallback-187-thread-1-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 278568 ERROR (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.ZkController :org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /live_nodes [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) [junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1532) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:340) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:337) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:337) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:673) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.createClusterStateWatchersAndUpdate(ZkStateReader.java:390) [junit4] 2> at org.apache.solr.cloud.ZkController$1.command(ZkController.java:320) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:168) [junit4] 2> at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:57) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:142) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [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:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 278568 ERROR (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.DefaultConnectionStrategy Reconnect to ZooKeeper failed:org.apache.solr.common.cloud.ZooKeeperException: [junit4] 2> at org.apache.solr.cloud.ZkController$1.command(ZkController.java:405) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:168) [junit4] 2> at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:57) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:142) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [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:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /live_nodes [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) [junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1532) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:340) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:337) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:337) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:673) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.createClusterStateWatchersAndUpdate(ZkStateReader.java:390) [junit4] 2> at org.apache.solr.cloud.ZkController$1.command(ZkController.java:320) [junit4] 2> ... 10 more [junit4] 2> [junit4] 2> 278569 WARN (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.DefaultConnectionStrategy Reconnect to ZooKeeper failed [junit4] 2> 278572 INFO (zkCallback-187-thread-5-processing-n:127.0.0.1:34199_solr) [n:127.0.0.1:34199_solr ] o.a.s.c.c.ConnectionManager Connected:true [junit4] 2> 278573 INFO (jetty-closer-181-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4fe6cf26{/solr,null,UNAVAILABLE} [junit4] 2> 278574 ERROR (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 278574 INFO (SUITE-HealthCheckHandlerTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38043 38043 [junit4] 2> 278662 INFO (Thread-278) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38043 38043 [junit4] 2> 278668 WARN (Thread-278) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 3 /solr/clusterprops.json [junit4] 2> 2 /solr/aliases.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 2 /solr/clusterstate.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 2 /solr/live_nodes [junit4] 2> 2 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.HealthCheckHandlerTest_3CBFC6A590224A60-001 [junit4] 2> Aug 08, 2017 11:53:39 AM 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): {}, docValues:{}, maxPointsInLeafNode=1685, maxMBSortInHeap=6.583876944425939, sim=RandomSimilarity(queryNorm=true): {}, locale=und, timezone=Antarctica/South_Pole [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=230283448,total=440401920 [junit4] 2> NOTE: All tests run in this JVM: [TestCoreContainer, TestCoreDiscovery, TestRTGBase, CollectionsAPIDistributedZkTest, ParsingFieldUpdateProcessorsTest, AssignTest, TestFieldCacheSort, CoreAdminHandlerTest, CachingDirectoryFactoryTest, NoCacheHeaderTest, TestBlendedInfixSuggestions, TestTolerantUpdateProcessorCloud, SoftAutoCommitTest, ConcurrentDeleteAndCreateCollectionTest, SegmentsInfoRequestHandlerTest, TestOrdValues, TestSimpleTrackingShardHandler, HealthCheckHandlerTest] [junit4] Completed [60/728 (1!)] on J1 in 3.21s, 3 tests, 1 error <<< FAILURES! [...truncated 366 lines...] [junit4] Suite: org.apache.solr.update.AutoCommitTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_3CBFC6A590224A60-001/init-core-data-001 [junit4] 2> 674956 WARN (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 674956 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 674974 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-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> 674974 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 674975 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 674975 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-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> 675056 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 675158 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 675311 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 675513 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3f8d7f6b [junit4] 2> 675534 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3f8d7f6b [junit4] 2> 675534 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3f8d7f6b [junit4] 2> 675548 INFO (coreLoadExecutor-1876-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> 675626 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 675670 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 675924 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 675946 INFO (coreLoadExecutor-1876-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> 675946 INFO (coreLoadExecutor-1876-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@3f8d7f6b [junit4] 2> 675946 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 675946 INFO (coreLoadExecutor-1876-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.AutoCommitTest_3CBFC6A590224A60-001/init-core-data-001/] [junit4] 2> 675970 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=13, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=35.984375, floorSegmentMB=1.6923828125, forceMergeDeletesPctAllowed=24.689205284784446, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3863109456151417 [junit4] 2> 676165 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 676165 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 676166 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 676166 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 676167 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=62.1455078125, floorSegmentMB=2.1103515625, forceMergeDeletesPctAllowed=0.19007260715851126, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 676168 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@41fb8497[collection1] main] [junit4] 2> 676169 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 676189 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 676231 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 676232 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 676232 INFO (coreLoadExecutor-1876-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575164173611958272 [junit4] 2> 676235 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 676235 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 676235 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 676235 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 676235 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 676235 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 676236 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 676236 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 676236 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 676238 INFO (searcherExecutor-1877-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41fb8497[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 676239 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin [junit4] 2> 676239 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 676240 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@49d3c00a[collection1] realtime] [junit4] 2> 676241 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] 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> 676241 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] 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> 676242 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] 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> 676332 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 676523 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 676806 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 676824 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ ] 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> 676869 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ 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@3f8d7f6b [junit4] 2> 676869 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ 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.AutoCommitTest_3CBFC6A590224A60-001/init-core-data-001/] [junit4] 2> 677025 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 677025 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 677027 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3cd69d46[collection1] main] [junit4] 2> 677028 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 677029 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 677032 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 677032 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575164174450819072 [junit4] 2> 677038 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 677047 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 677047 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 677047 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 677047 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 677061 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 677061 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=3.3798828125, floorSegmentMB=0.7001953125, forceMergeDeletesPctAllowed=12.80518516797666, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49950920676962773 [junit4] 2> 677061 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 677062 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 677062 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 677062 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 677065 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3cd69d46[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 677065 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72c1de8e[collection1] main] [junit4] 2> 677066 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@306c94a5 [junit4] 2> 677066 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=812422309 [junit4] 2> 677076 INFO (searcherExecutor-1882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72c1de8e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 677108 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1575164174494859264)]} 0 34 [junit4] 2> 677109 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ 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> 677112 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1575164174534705152)]} 0 38 [junit4] 2> 677113 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ 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> 677113 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1575164174535753728)]} 0 39 [junit4] 2> 677113 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ 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> 677114 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ 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> 677114 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ 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.AutoCommitTest.testCommitWithin(AutoCommitTest.java:353) [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> 677115 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testCommitWithin -Dtests.seed=3CBFC6A590224A60 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=zh-HK -Dtests.timezone=America/Grenada -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.88s J2 | AutoCommitTest.testCommitWithin <<< [junit4] > Throwable #1: java.lang.RuntimeException: Exception during query [junit4] > at __randomizedtesting.SeedInfo.seed([3CBFC6A590224A60:866DA9DD130CA475]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886) [junit4] > at org.apache.solr.update.AutoCommitTest.testCommitWithin(AutoCommitTest.java:353) [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> 677123 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime [junit4] 2> 677123 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 677123 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12e1aea5[collection1] realtime] [junit4] 2> 677123 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] 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 0 [junit4] 2> 677124 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] 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> 677124 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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> 677193 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 677290 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 677699 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 677709 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] 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> 677754 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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@3f8d7f6b [junit4] 2> 677755 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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.AutoCommitTest_3CBFC6A590224A60-001/init-core-data-001/] [junit4] 2> 678037 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 678037 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 678039 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@313696fc[collection1] main] [junit4] 2> 678040 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 678041 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 678052 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 678053 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 678053 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 678053 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 678053 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 678056 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 678057 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 678057 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 678073 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 678073 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 678076 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@313696fc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 678089 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=81.9853515625, floorSegmentMB=1.810546875, forceMergeDeletesPctAllowed=6.431913086621597, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7117900507582643 [junit4] 2> 678093 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 678097 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@953394f[collection1] main] [junit4] 2> 678097 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@437e4066 [junit4] 2> 678098 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1132347494 [junit4] 2> 678102 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@953394f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 678121 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1575164175576989696)]} 0 16 [junit4] 2> 678121 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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> 679607 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 679612 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5612451d[collection1] main] [junit4] 2> 679617 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5612451d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1)))} [junit4] 2> 679617 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 679701 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1575164177248419840)]} 0 1596 [junit4] 2> 679701 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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=1 status=0 QTime=0 [junit4] 2> 679702 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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> 679702 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[529 (-1575164177250516992)]} 0 0 [junit4] 2> 679703 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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=1 status=0 QTime=0 [junit4] 2> 681201 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 681203 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@28e4dc9[collection1] main] [junit4] 2> 681207 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 681208 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@28e4dc9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1)))} [junit4] 2> 681248 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[550 (1575164178870566912)]} 0 3143 [junit4] 2> 681248 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ 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> 681249 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178872664064)]} 0 3144 [junit4] 2> 681249 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178872664065)]} 0 3144 [junit4] 2> 681249 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178872664066)]} 0 3144 [junit4] 2> 681249 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178872664067)]} 0 3145 [junit4] 2> 681250 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178873712640)]} 0 3145 [junit4] 2> 681250 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178873712641)]} 0 3145 [junit4] 2> 681250 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178873712642)]} 0 3145 [junit4] 2> 681250 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178873712643)]} 0 3145 [junit4] 2> 681250 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178873712644)]} 0 3145 [junit4] 2> 681250 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1575164178873712645)]} 0 3146 [junit4] 2> 681251 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 682748 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 682760 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3096c818[collection1] main] [junit4] 2> 682768 INFO (searcherExecutor-1886-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3096c818[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1) Uninverting(_3(8.0.0):C11/9:delGen=1)))} [junit4] 2> 682769 INFO (commitScheduler-1889-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 683013 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[531 (1575164180721303552)]} 0 4908 [junit4] 2> 683013 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 683014 INFO (TEST-AutoCommitTest.testMaxTime-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime [junit4] 2> 683016 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs [junit4] 2> 683017 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 683017 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3da78987[collection1] realtime] [junit4] 2> 683017 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] 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 0 [junit4] 2> 683018 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] 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> 683019 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ 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> 683058 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 683082 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 683212 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 683222 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] 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> 683241 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ 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@3f8d7f6b [junit4] 2> 683241 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ 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.AutoCommitTest_3CBFC6A590224A60-001/init-core-data-001/] [junit4] 2> 683321 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 683321 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 683322 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ff98df2[collection1] main] [junit4] 2> 683323 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 683324 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 683331 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 683331 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 683337 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 683337 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 683337 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 683337 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 683338 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 683338 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 683342 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 683343 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 683346 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ff98df2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 683346 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.5576171875, noCFSRatio=1.0] [junit4] 2> 683347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 683347 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34f859b1[collection1] main] [junit4] 2> 683348 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50e380bb [junit4] 2> 683351 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34f859b1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 683351 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1357086907 [junit4] 2> 683363 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1575164181089353728)]} 0 0 [junit4] 2> 683364 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1575164181090402304)]} 0 1 [junit4] 2> 683364 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1575164181090402305)]} 0 1 [junit4] 2> 683366 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1575164181090402306)]} 0 3 [junit4] 2> 683366 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1575164181092499456)]} 0 3 [junit4] 2> 683367 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1575164181092499457)]} 0 4 [junit4] 2> 683367 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1575164181093548032)]} 0 4 [junit4] 2> 683367 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1575164181093548033)]} 0 4 [junit4] 2> 683367 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1575164181093548034)]} 0 4 [junit4] 2> 683367 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1575164181093548035)]} 0 4 [junit4] 2> 683368 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1575164181094596608)]} 0 5 [junit4] 2> 683368 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1575164181094596609)]} 0 5 [junit4] 2> 683368 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1575164181094596610)]} 0 5 [junit4] 2> 683368 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1575164181094596611)]} 0 5 [junit4] 2> 683369 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 683472 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1575164181095645184)]} 0 109 [junit4] 2> 683473 INFO (commitScheduler-1893-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 683480 INFO (commitScheduler-1893-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4eac090f[collection1] main] [junit4] 2> 683482 INFO (searcherExecutor-1890-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4eac090f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C15)))} [junit4] 2> 683482 INFO (commitScheduler-1893-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 683723 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1575164181465792512)]} 0 360 [junit4] 2> 683723 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0 [junit4] 2> 683724 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 683724 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[3CBFC6A590224A60]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs [junit4] 2> 683725 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 683725 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=568655596 [junit4] 2> 683725 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 683726 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 683728 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 683728 INFO (SUITE-AutoCommitTest-seed#[3CBFC6A590224A60]-worker) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 683745 INFO (coreCloseExecutor-1894-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@60764b97 [junit4] 2> 683759 INFO (coreCloseExecutor-1894-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1618365335 [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.AutoCommitTest_3CBFC6A590224A60-001 [junit4] 2> Aug 08, 2017 12:00:24 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), sim=RandomSimilarity(queryNorm=false): {}, locale=zh-HK, timezone=America/Grenada [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=230553392,total=458752000 [junit4] 2> NOTE: All tests run in this JVM: [TestStressLiveNodes, CoreMergeIndexesAdminHandlerTest, SpatialRPTFieldTypeTest, HardAutoCommitTest, TestXmlQParser, TestSolrConfigHandlerConcurrent, FastVectorHighlighterTest, TestCloudRecovery, OverseerTest, OverseerCollectionConfigSetProcessorTest, DistributedSuggestComponentTest, TestMissingGroups, HdfsChaosMonkeySafeLeaderTest, SimplePostToolTest, SortByFunctionTest, UniqFieldsUpdateProcessorFactoryTest, TestUnifiedSolrHighlighter, TestDynamicFieldResource, BadIndexSchemaTest, TestSQLHandler, V2StandaloneTest, TestConfigSetsAPIExclusivity, TestPKIAuthenticationPlugin, TestFieldCacheSortRandom, TestCustomStream, UpdateLogTest, SpellCheckCollatorWithCollapseTest, RecoveryAfterSoftCommitTest, TestLFUCache, TestScoreJoinQPScore, DocExpirationUpdateProcessorFactoryTest, TestConfigSetProperties, SearchHandlerTest, PingRequestHandlerTest, SimpleMLTQParserTest, HdfsRecoverLeaseTest, HdfsUnloadDistributedZkTest, TestFieldCache, TestSurroundQueryParser, TestSerializedLuceneMatchVersion, TestBackupRepositoryFactory, NodeMutatorTest, ClassificationUpdateProcessorIntegrationTest, TestManagedResource, ExitableDirectoryReaderTest, TestLMJelinekMercerSimilarityFactory, WrapperMergePolicyFactoryTest, TestInfoStreamLogging, MoveReplicaHDFSUlogDirTest, TestUtils, CdcrUpdateLogTest, CollectionsAPIAsyncDistributedZkTest, TestSolrDeletionPolicy2, RegexBoostProcessorTest, TestExportWriter, StatsComponentTest, TestJsonFacets, CollectionReloadTest, AutoCommitTest] [junit4] Completed [170/728 (2!)] on J2 in 9.10s, 3 tests, 1 error <<< FAILURES! [...truncated 45149 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
