Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2535/
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.io.graph.GraphTest Error Message: Error from server at https://127.0.0.1:33551/solr: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000000 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33551/solr: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000000 at __randomizedtesting.SeedInfo.seed([7F9746C299892580]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.client.solrj.io.graph.GraphTest.setupCluster(GraphTest.java:62) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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: junit.framework.TestSuite.org.apache.solr.client.solrj.io.stream.MathExpressionTest Error Message: Error from server at https://127.0.0.1:39622/solr: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000000 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:39622/solr: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000000 at __randomizedtesting.SeedInfo.seed([7F9746C299892580]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.client.solrj.io.stream.MathExpressionTest.setupCluster(MathExpressionTest.java:62) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 15941 lines...] [junit4] Suite: org.apache.solr.client.solrj.io.graph.GraphTest [junit4] 2> 230242 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/init-core-data-001 [junit4] 2> 230243 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 230245 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 230259 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/tempDir-001 [junit4] 2> 230259 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 230287 INFO (Thread-75) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 230287 INFO (Thread-75) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 230304 ERROR (Thread-75) [ ] 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> 230396 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:37649 [junit4] 2> 230616 INFO (zkConnectionManagerCallback-102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 230806 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 230824 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 230864 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 230864 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 230864 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 230880 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@36aba1dd{/solr,null,AVAILABLE} [junit4] 2> 230974 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 230974 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 230974 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 230990 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30979b5b{/solr,null,AVAILABLE} [junit4] 2> 231298 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@46d1af1f{SSL,[ssl, http/1.1]}{127.0.0.1:38154} [junit4] 2> 231298 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.Server Started @231667ms [junit4] 2> 231298 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38154} [junit4] 2> 231299 ERROR (jetty-launcher-99-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 231299 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 231299 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 231299 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 231299 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 231299 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-19T14:22:51.164Z [junit4] 2> 231408 INFO (zkConnectionManagerCallback-104-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 231411 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@774dfb7a{SSL,[ssl, http/1.1]}{127.0.0.1:33551} [junit4] 2> 231411 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.Server Started @231779ms [junit4] 2> 231411 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33551} [junit4] 2> 231412 ERROR (jetty-launcher-99-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 231412 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 231424 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 231481 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 231481 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 231481 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 231481 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-19T14:22:51.346Z [junit4] 2> 231555 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37649/solr [junit4] 2> 231612 INFO (zkConnectionManagerCallback-110-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 231628 INFO (zkConnectionManagerCallback-106-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 231646 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 231684 INFO (zkConnectionManagerCallback-112-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 231900 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37649/solr [junit4] 2> 232038 INFO (zkConnectionManagerCallback-116-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 232366 INFO (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 232501 WARN (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 232794 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 232830 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38154_solr [junit4] 2> 232904 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.Overseer Overseer (id=72652475522482180-127.0.0.1:38154_solr-n_0000000000) starting [junit4] 2> 232997 WARN (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 233256 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 233329 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33551_solr [junit4] 2> 233439 INFO (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 233508 INFO (zkCallback-111-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 233573 INFO (zkCallback-119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 233609 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 233756 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37649/solr ready [junit4] 2> 234137 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 234138 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38154_solr [junit4] 2> 234219 INFO (zkCallback-119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 234219 INFO (zkCallback-111-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 234893 INFO (zkCallback-126-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 235896 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33551.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 236005 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33551.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 236006 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33551.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 236007 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/tempDir-001/node1/. [junit4] 2> 236244 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 236416 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 236417 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:33551_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37649/solr ready [junit4] 2> 239168 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38154.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 239300 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38154.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 239301 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38154.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 239302 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:38154_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/tempDir-001/node2/. [junit4] 2> 239665 INFO (zkConnectionManagerCallback-134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 239815 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 239830 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 239849 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37649/solr ready [junit4] 2> 241953 INFO (qtp1905425546-569) [n:127.0.0.1:33551_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 242212 INFO (OverseerThreadFactory-372-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 243165 INFO (OverseerStateUpdate-72652475522482180-127.0.0.1:38154_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:33551/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 243236 INFO (OverseerStateUpdate-72652475522482180-127.0.0.1:38154_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection1_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:38154/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 244269 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr x:collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 244270 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr x:collection1_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 244545 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr x:collection1_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 245652 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 245671 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 245851 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test [junit4] 2> 245931 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test [junit4] 2> 251927 WARN (jetty-launcher-99-thread-2-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 8654ms for sessionid 0x1021d0aa3920007 [junit4] 2> 251944 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1021d0aa3920007, likely client has closed socket [junit4] 2> 252035 WARN (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@38be919 name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 252036 WARN (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 279266 WARN (jetty-launcher-99-thread-2-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 27321ms for sessionid 0x1021d0aa3920004 [junit4] 2> 279267 WARN (jetty-launcher-99-thread-1-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 33378ms for sessionid 0x1021d0aa3920006 [junit4] 2> 279267 WARN (SUITE-GraphTest-seed#[7F9746C299892580]-worker-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 27302ms for sessionid 0x1021d0aa392000a [junit4] 2> 279267 WARN (jetty-launcher-99-thread-1-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 27324ms for sessionid 0x1021d0aa3920008 [junit4] 2> 279284 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1021d0aa3920008, likely client has closed socket [junit4] 2> 279284 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1021d0aa3920006, likely client has closed socket [junit4] 2> 279284 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1021d0aa392000a, likely client has closed socket [junit4] 2> 279284 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1021d0aa3920004, likely client has closed socket [junit4] 2> 279393 WARN (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b69c64b name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 279393 WARN (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 279426 WARN (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70dba1fc name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 279426 WARN (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 279511 WARN (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6373295f name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 279511 WARN (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 279547 WARN (zkConnectionManagerCallback-112-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c759466 name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 279548 WARN (zkConnectionManagerCallback-112-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 280076 WARN (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly. [junit4] 2> 280115 WARN (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly. [junit4] 2> 280377 WARN (jetty-launcher-99-thread-2-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x1021d0aa3920007 has expired [junit4] 2> 280404 WARN (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@38be919 name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Expired type:None path:null path: null type: None [junit4] 2> 280405 WARN (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 280406 WARN (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> 280476 WARN (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 280476 WARN (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 280477 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 280491 WARN (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 280491 WARN (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 280491 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 280514 INFO (zkConnectionManagerCallback-127-thread-1-EventThread) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 280515 INFO (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished. [junit4] 2> 280528 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true [junit4] 2> 280547 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true [junit4] 2> 280548 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33551.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 280548 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 280548 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/tempDir-001/node1/./collection1_shard1_replica_n1/data/] [junit4] 2> 280563 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38154.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b548490 [junit4] 2> 280563 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 280563 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/tempDir-001/node2/collection1_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.graph.GraphTest_7F9746C299892580-001/tempDir-001/node2/./collection1_shard2_replica_n2/data/] [junit4] 2> 280624 INFO (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 280707 WARN (SUITE-GraphTest-seed#[7F9746C299892580]-worker-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x1021d0aa392000a has expired [junit4] 2> 280707 WARN (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6373295f name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Expired type:None path:null path: null type: None [junit4] 2> 280707 WARN (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 280708 WARN (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> 280726 INFO (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Reconnected to ZooKeeper [junit4] 2> 280726 INFO (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient Connected:true [junit4] 2> 280857 INFO (zkConnectionManagerCallback-139-thread-1-EventThread) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 280857 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished. [junit4] 2> 280925 WARN (jetty-launcher-99-thread-1-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x1021d0aa3920008 has expired [junit4] 2> 280925 WARN (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b69c64b name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Expired type:None path:null path: null type: None [junit4] 2> 280925 WARN (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 280925 WARN (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> 281021 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 281038 INFO (zkConnectionManagerCallback-112-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 281055 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Reconnected to ZooKeeper [junit4] 2> 281055 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient Connected:true [junit4] 2> 281112 INFO (zkCallback-111-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 281124 INFO (zkConnectionManagerCallback-132-thread-1-EventThread) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 281181 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished. [junit4] 2> 281328 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 281364 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Reconnected to ZooKeeper [junit4] 2> 281364 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient Connected:true [junit4] 2> 281381 WARN (jetty-launcher-99-thread-1-SendThread(127.0.0.1:37649)) [ ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x1021d0aa3920006 has expired [junit4] 2> 281382 WARN (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70dba1fc name: ZooKeeperConnection Watcher:127.0.0.1:37649/solr got event WatchedEvent state:Expired type:None path:null path: null type: None [junit4] 2> 281382 WARN (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 281382 WARN (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> 281417 INFO (zkConnectionManagerCallback-120-thread-1-EventThread) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 281461 INFO (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished. [junit4] 2> 281461 INFO (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.ZkController ZooKeeper session re-connected ... refreshing core states after session expiration. [junit4] 2> 281637 INFO (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 281658 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 281658 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 281659 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 281660 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 281762 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 281762 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 281764 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 281764 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 281802 INFO (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33551_solr [junit4] 2> 281873 INFO (zkCallback-131-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 281880 INFO (zkCallback-126-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 281874 INFO (zkCallback-111-thread-6) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 281873 INFO (zkCallback-138-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 282076 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@356c3864[collection1_shard1_replica_n1] main] [junit4] 2> 282075 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e6f81ff[collection1_shard2_replica_n2] main] [junit4] 2> 281982 INFO (zkCallback-119-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 281982 INFO (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Reconnected to ZooKeeper [junit4] 2> 282096 INFO (zkConnectionManagerCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient Connected:true [junit4] 2> 282099 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 282117 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 282133 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 282133 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 282153 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 282155 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 282155 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1600902735614443520 [junit4] 2> 282161 INFO (searcherExecutor-378-thread-1-processing-n:127.0.0.1:38154_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@6e6f81ff[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 282169 INFO (searcherExecutor-377-thread-1-processing-n:127.0.0.1:33551_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@356c3864[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 282169 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1600902735629123584 [junit4] 2> 282575 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 282610 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0} [junit4] 2> 282662 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 282662 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 282663 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33551/solr/collection1_shard1_replica_n1/ [junit4] 2> 282684 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 282684 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 282684 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38154/solr/collection1_shard2_replica_n2/ [junit4] 2> 282684 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 282684 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 282701 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:38154/solr/collection1_shard2_replica_n2/ has no replicas [junit4] 2> 282702 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 282718 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:33551/solr/collection1_shard1_replica_n1/ has no replicas [junit4] 2> 282718 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 282866 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38154/solr/collection1_shard2_replica_n2/ shard2 [junit4] 2> 282883 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:33551/solr/collection1_shard1_replica_n1/ shard1 [junit4] 2> 283084 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 283128 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 283167 INFO (qtp1428074433-561) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=38622 [junit4] 2> 283185 INFO (qtp1905425546-568) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=38916 [junit4] 2> 283295 INFO (OverseerThreadFactory-372-thread-1) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 283370 INFO (zkCallback-111-thread-6) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 283379 INFO (zkCallback-119-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 285119 INFO (OverseerCollectionConfigSetProcessor-72652475522482180-127.0.0.1:38154_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 422047 ERROR (qtp1905425546-569) [n:127.0.0.1:33551_solr ] o.a.s.h.RequestHandlerBase org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000000 [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340) [junit4] 2> at org.apache.solr.cloud.OverseerTaskQueue.offer(OverseerTaskQueue.java:210) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.sendToOCPQueue(CollectionsHandler.java:348) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:258) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:232) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:734) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:715) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:378) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:324) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) [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:1642) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:531) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:291) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:151) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:760) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:678) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 422047 INFO (qtp1905425546-569) [n:127.0.0.1:33551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=500 QTime=180094 [junit4] 2> 422047 ERROR (qtp1905425546-569) [n:127.0.0.1:33551_solr ] o.a.s.s.HttpSolrCall null:org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000000 [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340) [junit4] 2> at org.apache.solr.cloud.OverseerTaskQueue.offer(OverseerTaskQueue.java:210) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.sendToOCPQueue(CollectionsHandler.java:348) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:258) [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:232) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:734) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:715) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:378) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:324) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) [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:1642) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:531) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:291) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:151) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:760) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:678) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 422221 INFO (jetty-closer-100-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@46d1af1f{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 422223 INFO (jetty-closer-100-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1121472569 [junit4] 2> 422223 INFO (jetty-closer-100-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 422223 INFO (jetty-closer-100-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b31f92d: rootName = solr_38154, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3f60d990 [junit4] 2> 422244 INFO (jetty-closer-100-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 422244 INFO (jetty-closer-100-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@146f694d: rootName = solr_38154, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2d43d0e6 [junit4] 2> 422279 INFO (jetty-closer-100-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 422279 INFO (jetty-closer-100-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35634c5: rootName = solr_38154, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@79022706 [junit4] 2> 422282 INFO (jetty-closer-100-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@774dfb7a{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 422295 INFO (jetty-closer-100-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=277590539 [junit4] 2> 422295 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 422295 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f1303c4: rootName = solr_33551, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4428df10 [junit4] 2> 422317 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 422317 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@cafcefe: rootName = solr_33551, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2d43d0e6 [junit4] 2> 422335 INFO (jetty-closer-100-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38154_solr [junit4] 2> 422336 INFO (jetty-closer-100-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 422353 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 422354 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d7f40de: rootName = solr_33551, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@79022706 [junit4] 2> 422371 INFO (zkCallback-111-thread-7) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 422505 INFO (zkCallback-126-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 422513 INFO (zkCallback-119-thread-7) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 422517 INFO (zkCallback-131-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 422517 INFO (zkCallback-111-thread-8) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 422659 INFO (coreCloseExecutor-387-thread-1) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@d4b8974 [junit4] 2> 422659 INFO (coreCloseExecutor-387-thread-1) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n2, tag=d4b8974 [junit4] 2> 422660 INFO (coreCloseExecutor-387-thread-1) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5ba99ca4: rootName = solr_38154, domain = solr.core.collection1.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.collection1.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@7cbc88c2 [junit4] 2> 422675 INFO (zkCallback-119-thread-6) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 422696 INFO (jetty-closer-100-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:33551_solr [junit4] 2> 422705 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 422706 INFO (zkCallback-126-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 422706 INFO (zkCallback-119-thread-6) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 422719 INFO (coreCloseExecutor-387-thread-1) [n:127.0.0.1:38154_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader, tag=d4b8974 [junit4] 2> 422739 INFO (coreCloseExecutor-388-thread-1) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@3138b9ad [junit4] 2> 422739 INFO (coreCloseExecutor-388-thread-1) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.replica_n1, tag=3138b9ad [junit4] 2> 422739 INFO (coreCloseExecutor-388-thread-1) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@aa6bbd5: rootName = solr_33551, domain = solr.core.collection1.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection1.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@79105e27 [junit4] 2> 422748 INFO (zkCallback-111-thread-9) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 422753 INFO (zkCallback-131-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 422901 INFO (coreCloseExecutor-388-thread-1) [n:127.0.0.1:33551_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=3138b9ad [junit4] 2> 422956 INFO (zkCallback-119-thread-6) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [0]) [junit4] 2> 422968 INFO (zkCallback-111-thread-9) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [0]) [junit4] 2> 423047 INFO (jetty-closer-100-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72652475522482180-127.0.0.1:38154_solr-n_0000000000) closing [junit4] 2> 423047 INFO (OverseerStateUpdate-72652475522482180-127.0.0.1:38154_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38154_solr [junit4] 2> 423090 WARN (OverseerAutoScalingTriggerThread-72652475522482180-127.0.0.1:38154_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 423156 INFO (jetty-closer-100-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@30979b5b{/solr,null,UNAVAILABLE} [junit4] 2> 423156 INFO (jetty-closer-100-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 423230 INFO (jetty-closer-100-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@36aba1dd{/solr,null,UNAVAILABLE} [junit4] 2> 423230 INFO (jetty-closer-100-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 423249 ERROR (SUITE-GraphTest-seed#[7F9746C299892580]-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> 423249 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37649 37649 [junit4] 2> 423558 INFO (Thread-75) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37649 37649 [junit4] 2> 423558 WARN (Thread-75) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 9 /solr/aliases.json [junit4] 2> 3 /solr/security.json [junit4] 2> 2 /solr/collections/collection1/terms/shard2 [junit4] 2> 2 /solr/configs/conf [junit4] 2> 2 /solr/collections/collection1/terms/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 9 /solr/clusterstate.json [junit4] 2> 9 /solr/clusterprops.json [junit4] 2> 2 /solr/collections/collection1/state.json [junit4] 2> 2 /solr/overseer_elect/election/72652475522482180-127.0.0.1:38154_solr-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 9 /solr/live_nodes [junit4] 2> 9 /solr/collections [junit4] 2> [junit4] 2> 423559 INFO (SUITE-GraphTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> May 19, 2018 2:26:03 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=Lucene70, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@25eee26c), locale=be-BY, timezone=Africa/Maseru [junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=4,threads=1,free=219140080,total=392691712 [junit4] 2> NOTE: All tests run in this JVM: [TestBatchUpdate, HyperbolicSineEvaluatorTest, HttpSolrClientBuilderTest, LessThanEqualToEvaluatorTest, NoOpResponseParserTest, StreamExpressionToExplanationTest, TestSuggesterResponse, SolrParamTest, SineEvaluatorTest, TangentEvaluatorTest, GreaterThanEqualToEvaluatorTest, TestCoreAdmin, NamedListTest, JettyWebappTest, HttpClientUtilTest, PowerEvaluatorTest, TestHash, SolrExampleBinaryTest, CloudSolrClientBuilderTest, TestCloudSolrClientConnections, GraphTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=GraphTest -Dtests.seed=7F9746C299892580 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=be-BY -Dtests.timezone=Africa/Maseru -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J2 | GraphTest (suite) <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33551/solr: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000000 [junit4] > at __randomizedtesting.SeedInfo.seed([7F9746C299892580]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) [junit4] > at org.apache.solr.client.solrj.io.graph.GraphTest.setupCluster(GraphTest.java:62) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [66/152 (1!)] on J2 in 194.35s, 0 tests, 1 error <<< FAILURES! [...truncated 94 lines...] [junit4] Suite: org.apache.solr.client.solrj.io.stream.MathExpressionTest [junit4] 2> 389192 INFO (SUITE-MathExpressionTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_7F9746C299892580-001/init-core-data-001 [junit4] 2> 389192 INFO (SUITE-MathExpressionTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 389193 INFO (SUITE-MathExpressionTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 389195 INFO (SUITE-MathExpressionTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_7F9746C299892580-001/tempDir-001 [junit4] 2> 389195 INFO (SUITE-MathExpressionTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 389212 INFO (Thread-433) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 389212 INFO (Thread-433) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 389229 ERROR (Thread-433) [ ] 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> 389312 INFO (SUITE-MathExpressionTest-seed#[7F9746C299892580]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:39271 [junit4] 2> 389496 INFO (zkConnectionManagerCallback-681-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 389538 INFO (jetty-launcher-678-thread-2) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 389539 INFO (jetty-launcher-678-thread-1) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 389590 INFO (jetty-launcher-678-thread-4) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 389646 INFO (jetty-launcher-678-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 389647 INFO (jetty-launcher-678-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 389647 INFO (jetty-launcher-678-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 389648 INFO (jetty-launcher-678-thread-3) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 389651 INFO (jetty-launcher-678-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@790c786b{/solr,null,AVAILABLE} [junit4] 2> 389725 INFO (jetty-launcher-678-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 389725 INFO (jetty-launcher-678-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 389725 INFO (jetty-launcher-678-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 389725 INFO (jetty-launcher-678-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b20b2db{/solr,null,AVAILABLE} [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2c2b5d0b{SSL,[ssl, http/1.1]}{127.0.0.1:41156} [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.e.j.s.Server Started @390103ms [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41156} [junit4] 2> 389727 ERROR (jetty-launcher-678-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 389727 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-19T14:25:29.563Z [junit4] 2> 389747 INFO (jetty-launcher-678-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 389747 INFO (jetty-launcher-678-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 389747 INFO (jetty-launcher-678-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 389747 INFO (jetty-launcher-678-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e37b8d{/solr,null,AVAILABLE} [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@428c8d{SSL,[ssl, http/1.1]}{127.0.0.1:35137} [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.e.j.s.Server Started @390125ms [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35137} [junit4] 2> 389748 ERROR (jetty-launcher-678-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 389748 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-19T14:25:29.584Z [junit4] 2> 389814 INFO (jetty-launcher-678-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@317a523{SSL,[ssl, http/1.1]}{127.0.0.1:35667} [junit4] 2> 389814 INFO (jetty-launcher-678-thread-4) [ ] o.e.j.s.Server Started @390191ms [junit4] 2> 389814 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35667} [junit4] 2> 389814 ERROR (jetty-launcher-678-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 389814 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 389814 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 389815 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 389815 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 389815 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-19T14:25:29.651Z [junit4] 2> 389829 INFO (jetty-launcher-678-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 389830 INFO (jetty-launcher-678-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 389830 INFO (jetty-launcher-678-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 389831 INFO (jetty-launcher-678-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3e7116a2{/solr,null,AVAILABLE} [junit4] 2> 389831 INFO (jetty-launcher-678-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7afe7577{SSL,[ssl, http/1.1]}{127.0.0.1:39622} [junit4] 2> 389831 INFO (jetty-launcher-678-thread-2) [ ] o.e.j.s.Server Started @390208ms [junit4] 2> 389831 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39622} [junit4] 2> 389832 ERROR (jetty-launcher-678-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 389832 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 389832 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 389832 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 389832 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 389832 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-19T14:25:29.668Z [junit4] 2> 389863 INFO (zkConnectionManagerCallback-689-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 389863 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 389865 INFO (zkConnectionManagerCallback-685-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 389865 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 389878 INFO (zkConnectionManagerCallback-687-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 389879 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 389899 INFO (zkConnectionManagerCallback-683-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 389907 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 390007 INFO (jetty-launcher-678-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39271/solr [junit4] 2> 390089 INFO (zkConnectionManagerCallback-693-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 390167 INFO (jetty-launcher-678-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39271/solr [junit4] 2> 390226 INFO (jetty-launcher-678-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39271/solr [junit4] 2> 390268 INFO (zkConnectionManagerCallback-703-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 390285 INFO (zkConnectionManagerCallback-697-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 390403 INFO (zkConnectionManagerCallback-699-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 390485 INFO (jetty-launcher-678-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39271/solr [junit4] 2> 390557 INFO (zkConnectionManagerCallback-711-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [...truncated too long message...] ings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1432379795 [copy] Copying 39 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1432379795 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1432379795 resolve-example: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: resolve-server: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null655049244 [copy] Copying 246 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null655049244 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null655049244 check-working-copy: [ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public [ivy:cachepath] found com.jcraft#jsch;0.1.53 in public [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public [ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public [ivy:cachepath] found commons-codec#commons-codec;1.6 in public [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 43ms :: artifacts dl 1ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 8 | 0 | 0 | 0 || 8 | 0 | --------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". [wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation [wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 273 minutes 2 seconds Archiving artifacts WARN: No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? WARN: java.lang.InterruptedException: no matches found within 10000 Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures)
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
